na_ontap_snapmirror sometimes shows a NoneType error

[VirtSyntaxError]

Summary

Output from Ansible:
{
"module_stdout": "",
"module_stderr": "Traceback (most recent call last):\n File "/root/.ansible/tmp/ansible-tmp-1781152826.6933818-406-71063628751684/AnsiballZ_na_ontap_snapmirror.py", line 107, in \n _ansiballz_main()\n File "/root/.ansible/tmp/ansible-tmp-1781152826.6933818-406-71063628751684/AnsiballZ_na_ontap_snapmirror.py", line 99, in ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/root/.ansible/tmp/ansible-tmp-1781152826.6933818-406-71063628751684/AnsiballZ_na_ontap_snapmirror.py", line 47, in invoke_module\n runpy.run_module(mod_name='ansible_collections.netapp.ontap.plugins.modules.na_ontap_snapmirror', init_globals=dict(module_fqn='ansible_collections.netapp.ontap.plugins.modules.na_ontap_snapmirror', modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in run_module_code\n run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in run_code\n exec(code, run_globals)\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1803, in \n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1799, in main\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1777, in apply\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1741, in take_actions\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 884, in snapmirror_create\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1529, in snapmirror_rest_create\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1064, in snapmirror_initialize\nTypeError: 'NoneType' object is not subscriptable\n",
"exception": "Traceback (most recent call last):\n File "/root/.ansible/tmp/ansible-tmp-1781152826.6933818-406-71063628751684/AnsiballZ_na_ontap_snapmirror.py", line 107, in \n _ansiballz_main()\n File "/root/.ansible/tmp/ansible-tmp-1781152826.6933818-406-71063628751684/AnsiballZ_na_ontap_snapmirror.py", line 99, in ansiballz_main\n invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n File "/root/.ansible/tmp/ansible-tmp-1781152826.6933818-406-71063628751684/AnsiballZ_na_ontap_snapmirror.py", line 47, in invoke_module\n runpy.run_module(mod_name='ansible_collections.netapp.ontap.plugins.modules.na_ontap_snapmirror', init_globals=dict(module_fqn='ansible_collections.netapp.ontap.plugins.modules.na_ontap_snapmirror', modlib_path=modlib_path),\n File "/usr/lib64/python3.9/runpy.py", line 225, in run_module\n return run_module_code(code, init_globals, run_name, mod_spec)\n File "/usr/lib64/python3.9/runpy.py", line 97, in run_module_code\n run_code(code, mod_globals, init_globals,\n File "/usr/lib64/python3.9/runpy.py", line 87, in run_code\n exec(code, run_globals)\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1803, in \n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1799, in main\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1777, in apply\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1741, in take_actions\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 884, in snapmirror_create\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1529, in snapmirror_rest_create\n File "/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_3t2kllx/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py", line 1064, in snapmirror_initialize\nTypeError: 'NoneType' object is not subscriptable\n",
"msg": "MODULE FAILURE\nSee stdout/stderr for the exact error",
"rc": 1,
"_ansible_no_log": false,
"changed": false,
"_ansible_delegated_vars": {
"ansible_host": "localhost",
"ansible_port": null,
"ansible_user": "root",
"ansible_connection": "local"
}
}

Component Name

na_ontap_snapmirror

Ansible Version

Ansible Automation Platform Controller 4.5.12

ONTAP Collection Version

netapp.ontap 23.3.0

ONTAP Version

NetApp Release 9.16.1P11: Thu Jan 15 11:21:38 UTC 2026

Playbook

- name: Create SnapMirror
    netapp.ontap.na_ontap_snapmirror:
      state: present
      source_endpoint:
        path: "{{ svm_name }}:{{ primary_vol_name }}"
      destination_endpoint:
        path: "{{ paas_backup_svm_name }}:{{ backup_vol_name }}"
      schedule: "{{ play_vault_schedule }}"
      policy: "{{ snapmirror_policy }}"
      initialize: True
      transferring_time_out: 600
    delegate_to: localhost
    when: input_environment not in play_lab_environment and (paas_backup_cluster_name in inventory_hostname)

Steps to Reproduce

random -> 80% works

Expected Results

SnapMirror will be created and initialized

Actual Results

SnapMirror is created but not initialized

[csahu9]

@VirtSyntaxError hi, could you enable the REST API tracing and share /tmp/ontap_apis.log content that shows the calls being sent to REST and the responses/errors received?

[VirtSyntaxError]

@VirtSyntaxError hi, could you enable the REST API tracing and share /tmp/ontap_apis.log content that shows the calls being sent to REST and the responses/errors received?

I tried it a few times, no error. Will retry it until I get the error again.

[VirtSyntaxError]

@VirtSyntaxError hi, could you enable the REST API tracing and share /tmp/ontap_apis.log content that shows the calls being sent to REST and the responses/errors received?
Actually I don't see any errors in the REST API output below.

Hi @csahu9
I finally managed to get an error after 10-12 tries.

Error output:

{
  "module_stdout": "",
  "module_stderr": "Traceback (most recent call last):\n  File \"/root/.ansible/tmp/ansible-tmp-1782128159.3199894-139-189708791771992/AnsiballZ_na_ontap_snapmirror.py\", line 107, in <module>\n    _ansiballz_main()\n  File \"/root/.ansible/tmp/ansible-tmp-1782128159.3199894-139-189708791771992/AnsiballZ_na_ontap_snapmirror.py\", line 99, in _ansiballz_main\n    invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n  File \"/root/.ansible/tmp/ansible-tmp-1782128159.3199894-139-189708791771992/AnsiballZ_na_ontap_snapmirror.py\", line 47, in invoke_module\n    runpy.run_module(mod_name='ansible_collections.netapp.ontap.plugins.modules.na_ontap_snapmirror', init_globals=dict(_module_fqn='ansible_collections.netapp.ontap.plugins.modules.na_ontap_snapmirror', _modlib_path=modlib_path),\n  File \"/usr/lib64/python3.9/runpy.py\", line 225, in run_module\n    return _run_module_code(code, init_globals, run_name, mod_spec)\n  File \"/usr/lib64/python3.9/runpy.py\", line 97, in _run_module_code\n    _run_code(code, mod_globals, init_globals,\n  File \"/usr/lib64/python3.9/runpy.py\", line 87, in _run_code\n    exec(code, run_globals)\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1803, in <module>\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1799, in main\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1777, in apply\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1741, in take_actions\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 884, in snapmirror_create\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1529, in snapmirror_rest_create\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1064, in snapmirror_initialize\nTypeError: 'NoneType' object is not subscriptable\n",
  "exception": "Traceback (most recent call last):\n  File \"/root/.ansible/tmp/ansible-tmp-1782128159.3199894-139-189708791771992/AnsiballZ_na_ontap_snapmirror.py\", line 107, in <module>\n    _ansiballz_main()\n  File \"/root/.ansible/tmp/ansible-tmp-1782128159.3199894-139-189708791771992/AnsiballZ_na_ontap_snapmirror.py\", line 99, in _ansiballz_main\n    invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)\n  File \"/root/.ansible/tmp/ansible-tmp-1782128159.3199894-139-189708791771992/AnsiballZ_na_ontap_snapmirror.py\", line 47, in invoke_module\n    runpy.run_module(mod_name='ansible_collections.netapp.ontap.plugins.modules.na_ontap_snapmirror', init_globals=dict(_module_fqn='ansible_collections.netapp.ontap.plugins.modules.na_ontap_snapmirror', _modlib_path=modlib_path),\n  File \"/usr/lib64/python3.9/runpy.py\", line 225, in run_module\n    return _run_module_code(code, init_globals, run_name, mod_spec)\n  File \"/usr/lib64/python3.9/runpy.py\", line 97, in _run_module_code\n    _run_code(code, mod_globals, init_globals,\n  File \"/usr/lib64/python3.9/runpy.py\", line 87, in _run_code\n    exec(code, run_globals)\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1803, in <module>\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1799, in main\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1777, in apply\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1741, in take_actions\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 884, in snapmirror_create\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1529, in snapmirror_rest_create\n  File \"/tmp/ansible_netapp.ontap.na_ontap_snapmirror_payload_e8_4xj63/ansible_netapp.ontap.na_ontap_snapmirror_payload.zip/ansible_collections/netapp/ontap/plugins/modules/na_ontap_snapmirror.py\", line 1064, in snapmirror_initialize\nTypeError: 'NoneType' object is not subscriptable\n",
  "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error",
  "rc": 1,
  "_ansible_no_log": false,
  "changed": false,
  "_ansible_delegated_vars": {
    "ansible_host": "localhost",
    "ansible_port": null,
    "ansible_user": "root",
    "ansible_connection": "local"
  }
}

Logfile output:

{
  "lookup('ansible.builtin.file', '/tmp/ontap_apis.log')": "
  2026-06-22 11:35:59,814 DEBUG    sending: {'method': 'GET', 'url': 'https://cluster01.test.local/api/cluster', 'verify': False, 'params': {'fields': ['version']}, 'timeout': 60, 'json': None, 'headers': 'redacted', 'auth_args': 'redacted'}
  2026-06-22 11:35:59,814 DEBUG    Starting new HTTPS connection (1): cluster01.test.local:443\n2026-06-22 11:35:59,859 DEBUG    https://cluster01.test.local:443 \"GET /api/cluster?fields=version HTTP/1.1\" 200 168
  2026-06-22 11:35:59,860 DEBUG    200: b'{\\n  \"version\": {\\n    \"full\": \"NetApp Release 9.16.1P11: Thu Jan 15 11:21:38 UTC 2026\",\\n    \"generation\": 9,\\n    \"major\": 16,\\n    \"minor\": 1\\n  },\\n  \"_links\": {\\n    \"self\": {\\n      \"href\": \"/api/cluster\"\\n    }\\n  }\\n}'
  2026-06-22 11:35:59,861 DEBUG    sending: {'method': 'GET', 'url': 'https://cluster01.test.local/api/snapmirror/relationships', 'verify': False, 'params': {'destination.path': 'svm01:volume01_SV_LCK', 'fields': 'uuid,state,transfer.state,transfer.uuid,policy.name,policy.type,unhealthy_reason.message,healthy,source,transfer_schedule'}, 'timeout': 60, 'json': None, 'headers': 'redacted', 'auth_args': 'redacted'}
  2026-06-22 11:35:59,862 DEBUG    Starting new HTTPS connection (1): cluster01.test.local:443\n2026-06-22 11:36:00,049 DEBUG    https://cluster01.test.local:443 \"GET /api/snapmirror/relationships?destination.path=svm01%3Avolume01_SV_LCK&fields=uuid%2Cstate%2Ctransfer.state%2Ctransfer.uuid%2Cpolicy.name%2Cpolicy.type%2Cunhealthy_reason.message%2Chealthy%2Csource%2Ctransfer_schedule HTTP/1.1\" 200 226
  2026-06-22 11:36:00,050 DEBUG    200: b'{\\n  \"records\": [\\n  ],\\n  \"num_records\": 0,\\n  \"_links\": {\\n    \"self\": {\\n      \"href\": \"/api/snapmirror/relationships?destination.path=svm01%3Avolume01_SV_LCK&fields=uuid%2Cstate%2Ctransfer.state%2Ctransfer.uuid%2Cpolicy.name%2Cpolicy.type%2Cunhealthy_reason.message%2Chealthy%2Csource%2Ctransfer_schedule\"\\n    }\\n  }\\n}'
  2026-06-22 11:36:00,051 DEBUG    sending: {'method': 'POST', 'url': 'https://cluster01.test.local/api/snapmirror/relationships', 'verify': False, 'params': {'return_timeout': 120}, 'timeout': 60, 'json': {'source': {'path': 'source_svm01:volume01'}, 'destination': {'path': 'svm01:volume01_SV_LCK'}, 'policy': {'name': 'policy01'}, 'transfer_schedule': {'name': 'weekly'}}, 'headers': 'redacted', 'auth_args': 'redacted'}
  2026-06-22 11:36:00,052 DEBUG    Starting new HTTPS connection (1): cluster01.test.local:443
  2026-06-22 11:36:00,232 DEBUG    https://cluster01.test.local:443 \"POST /api/snapmirror/relationships?return_timeout=120 HTTP/1.1\" 201 189\n2026-06-22 11:36:00,232 DEBUG    201: b'{\\n  \"job\": {\\n    \"uuid\": \"8ac5c27c-6e2e-11f1-91b2-d039eac119b1\",\\n    \"_links\": {\\n      \"self\": {\\n        \"href\": \"/api/cluster/jobs/8ac5c27c-6e2e-11f1-91b2-d039eac119b1\"\\n      }\\n    }\\n  }\\n}'
  2026-06-22 11:36:00,233 DEBUG    sending: {'method': 'GET', 'url': 'https://cluster01.test.local/api/cluster/jobs/8ac5c27c-6e2e-11f1-91b2-d039eac119b1', 'verify': False, 'params': None, 'timeout': 60, 'json': None, 'headers': 'redacted', 'auth_args': 'redacted'}
  2026-06-22 11:36:00,234 DEBUG    Starting new HTTPS connection (1): cluster01.test.local:443\n2026-06-22 11:36:00,285 DEBUG    https://cluster01.test.local:443 \"GET /api/cluster/jobs/8ac5c27c-6e2e-11f1-91b2-d039eac119b1 HTTP/1.1\" 200 283
  2026-06-22 11:36:00,285 DEBUG    200: b'{\\n  \"uuid\": \"8ac5c27c-6e2e-11f1-91b2-d039eac119b1\",\\n  \"description\": \"POST /api/snapmirror/relationships/8ac5bbca-6e2e-11f1-91b2-d039eac119b1\",\\n  \"state\": \"success\",\\n  \"message\": \"success\",\\n  \"code\": 0,\\n  \"start_time\": \"2026-06-22T13:36:00+02:00\",\\n  \"end_time\": \"2026-06-22T13:36:00+02:00\",\\n  \"svm\": {\\n    \"name\": \"svm01\",\\n    \"uuid\": \"ac72dfa0-01d0-11f1-9aac-d039eac119b1\",\\n    \"_links\": {\\n      \"self\": {\\n        \"href\": \"/api/svm/svms/ac72dfa0-01d0-11f1-9aac-d039eac119b1\"\\n      }\\n    }\\n  },\\n  \"_links\": {\\n    \"self\": {\\n      \"href\": \"/api/cluster/jobs/8ac5c27c-6e2e-11f1-91b2-d039eac119b1\"\\n    }\\n  }\\n}'
  2026-06-22 11:36:00,286 DEBUG    sending: {'method': 'GET', 'url': 'https://cluster01.test.local/api/snapmirror/relationships', 'verify': False, 'params': {'destination.path': 'svm01:volume01_SV_LCK', 'fields': 'uuid,state,transfer.state,transfer.uuid,policy.name,policy.type,unhealthy_reason.message,healthy,source,transfer_schedule'}, 'timeout': 60, 'json': None, 'headers': 'redacted', 'auth_args': 'redacted'}
  2026-06-22 11:36:00,287 DEBUG    Starting new HTTPS connection (1): cluster01.test.local:443\n2026-06-22 11:36:00,474 DEBUG    https://cluster01.test.local:443 \"GET /api/snapmirror/relationships?destination.path=svm01%3Avolume01_SV_LCK&fields=uuid%2Cstate%2Ctransfer.state%2Ctransfer.uuid%2Cpolicy.name%2Cpolicy.type%2Cunhealthy_reason.message%2Chealthy%2Csource%2Ctransfer_schedule HTTP/1.1\" 200 226
  2026-06-22 11:36:00,474 DEBUG    200: b'{\\n  \"records\": [\\n  ],\\n  \"num_records\": 0,\\n  \"_links\": {\\n    \"self\": {\\n      \"href\": \"/api/snapmirror/relationships?destination.path=svm01%3Avolume01_SV_LCK&fields=uuid%2Cstate%2Ctransfer.state%2Ctransfer.uuid%2Cpolicy.name%2Cpolicy.type%2Cunhealthy_reason.message%2Chealthy%2Csource%2Ctransfer_schedule\"\\n    }\\n  }\\n}'",
  "_ansible_verbose_always": true,
  "_ansible_no_log": false,
  "changed": false
}

ONTAP CLI:

cluster01::*> snapmirror show *volume01*
                                                                       Progress
Source            Destination Mirror  Relationship   Total             Last
Path        Type  Path        State   Status         Progress  Healthy Updated
----------- ---- ------------ ------- -------------- --------- ------- --------
source_svm01:volume01 XDP svm01:volume01_SV_LCK Uninitialized Idle - true -

[csahu9]

Thanks for sharing the logs.
What I found is that for such random errors the REST GET is not able to fetch the relationship info right after creating the relationship.
Let us try to add some retry around this.