2019-05-09 00:22:59,420 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-09 00:23:49,471 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-09 00:24:39,516 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-09 00:25:29,564 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-09 00:26:19,614 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-09 00:28:26,960 [salt.utils.decorators:613 ][WARNING ][2739] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:28:27,567 [salt.utils.decorators:613 ][WARNING ][2739] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:28:29,642 [salt.loaded.int.states.file:2298][WARNING ][2885] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-09 00:28:35,006 [salt.state       :2022][WARNING ][2988] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-09 00:28:37,548 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:28:49,622 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:29:23,436 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:29:24,304 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:29:28,292 [salt.loaded.ext.module.maasng:1008][WARNING ][2988] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-09 00:29:28,292 [salt.loaded.ext.module.maasng:1011][WARNING ][2988] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-09 00:29:29,095 [salt.loaded.ext.module.maasng:1235][WARNING ][2988] Ignoring parameter vlan:0
2019-05-09 00:29:30,013 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:29:35,613 [salt.utils.decorators:613 ][WARNING ][5921] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:29:35,694 [salt.loaded.ext.module.maas:412 ][WARNING ][5921] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 00:29:37,197 [salt.loaded.ext.module.maas:412 ][WARNING ][5921] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 00:29:38,342 [salt.loaded.ext.module.maas:412 ][WARNING ][5921] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 00:29:39,706 [salt.loaded.ext.module.maas:412 ][WARNING ][5921] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 00:29:41,078 [salt.loaded.ext.module.maas:412 ][WARNING ][5921] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 00:29:45,176 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6585] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-09 00:29:45,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6585] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-09 00:29:45,232 [salt.utils.parsers:1051][WARNING ][364] Minion received a SIGTERM. Exiting.
2019-05-09 00:29:46,300 [salt.cli.daemons :293 ][INFO    ][6639] Setting up the Salt Minion "mas01.mcp-odl-noha.local"
2019-05-09 00:29:46,398 [salt.cli.daemons :82  ][INFO    ][6639] Starting up the Salt Minion
2019-05-09 00:29:46,398 [salt.utils.event :1017][INFO    ][6639] Starting pull socket on /var/run/salt/minion/minion_event_0f3e0c7467_pull.ipc
2019-05-09 00:29:47,271 [salt.minion      :976 ][INFO    ][6639] Creating minion process manager
2019-05-09 00:29:48,609 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6639] Executing command ['date', '+%z'] in directory '/root'
2019-05-09 00:29:48,629 [salt.utils.schedule:568 ][INFO    ][6639] Updating job settings for scheduled job: __mine_interval
2019-05-09 00:29:48,630 [salt.minion      :1108][INFO    ][6639] Added mine.update to scheduler
2019-05-09 00:29:48,634 [salt.minion      :1975][INFO    ][6639] Minion is starting as user 'root'
2019-05-09 00:29:48,648 [salt.minion      :2336][INFO    ][6639] Minion is ready to receive requests!
2019-05-09 00:30:16,981 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509003016965274
2019-05-09 00:30:17,003 [salt.minion      :1432][INFO    ][6738] Starting a new job with PID 6738
2019-05-09 00:30:20,636 [salt.state       :915 ][INFO    ][6738] Loading fresh modules for state activity
2019-05-09 00:30:20,680 [salt.fileclient  :1219][INFO    ][6738] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-09 00:30:20,717 [salt.state       :1780][INFO    ][6738] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:30:20.717746
2019-05-09 00:30:20,718 [salt.state       :1813][INFO    ][6738] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 00:30:20,719 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6738] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 00:30:22,050 [salt.state       :300 ][INFO    ][6738] {'pid': 6745, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 00:30:22,051 [salt.state       :1951][INFO    ][6738] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:30:22.051587 duration_in_ms=1333.84
2019-05-09 00:30:22,054 [salt.state       :1780][INFO    ][6738] Running state [maas.wait_for_machine_status] at time 00:30:22.054000
2019-05-09 00:30:22,054 [salt.state       :1813][INFO    ][6738] Executing state module.run for [maas.wait_for_machine_status]
2019-05-09 00:30:22,055 [salt.utils.decorators:613 ][WARNING ][6738] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:30:23,239 [salt.loaded.ext.module.maas:1023][INFO    ][6738] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1498.82600999s left)
2019-05-09 00:30:32,032 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003032019396
2019-05-09 00:30:32,055 [salt.minion      :1432][INFO    ][6770] Starting a new job with PID 6770
2019-05-09 00:30:32,078 [salt.minion      :1711][INFO    ][6770] Returning information for job: 20190509003032019396
2019-05-09 00:30:54,224 [salt.loaded.ext.module.maas:1023][INFO    ][6738] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1467.84159398s left)
2019-05-09 00:31:02,096 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003102080197
2019-05-09 00:31:02,118 [salt.minion      :1432][INFO    ][6813] Starting a new job with PID 6813
2019-05-09 00:31:02,141 [salt.minion      :1711][INFO    ][6813] Returning information for job: 20190509003102080197
2019-05-09 00:31:25,432 [salt.loaded.ext.module.maas:1023][INFO    ][6738] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1436.63352299s left)
2019-05-09 00:31:32,309 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003132272489
2019-05-09 00:31:32,325 [salt.minion      :1432][INFO    ][6940] Starting a new job with PID 6940
2019-05-09 00:31:32,338 [salt.minion      :1711][INFO    ][6940] Returning information for job: 20190509003132272489
2019-05-09 00:31:56,771 [salt.loaded.ext.module.maas:1023][INFO    ][6738] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1405.29434395s left)
2019-05-09 00:32:02,336 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003202320965
2019-05-09 00:32:02,359 [salt.minion      :1432][INFO    ][7194] Starting a new job with PID 7194
2019-05-09 00:32:02,384 [salt.minion      :1711][INFO    ][7194] Returning information for job: 20190509003202320965
2019-05-09 00:32:28,484 [salt.loaded.ext.module.maas:1023][INFO    ][6738] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1373.58173394s left)
2019-05-09 00:32:32,400 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003232387614
2019-05-09 00:32:32,423 [salt.minion      :1432][INFO    ][7362] Starting a new job with PID 7362
2019-05-09 00:32:32,447 [salt.minion      :1711][INFO    ][7362] Returning information for job: 20190509003232387614
2019-05-09 00:33:00,379 [salt.loaded.ext.module.maas:1023][INFO    ][6738] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1341.68612003s left)
2019-05-09 00:33:02,455 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003302441849
2019-05-09 00:33:02,477 [salt.minion      :1432][INFO    ][7825] Starting a new job with PID 7825
2019-05-09 00:33:02,502 [salt.minion      :1711][INFO    ][7825] Returning information for job: 20190509003302441849
2019-05-09 00:33:32,531 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003332515627
2019-05-09 00:33:32,553 [salt.minion      :1432][INFO    ][8044] Starting a new job with PID 8044
2019-05-09 00:33:32,579 [salt.minion      :1711][INFO    ][8044] Returning information for job: 20190509003332515627
2019-05-09 00:33:33,651 [salt.state       :300 ][INFO    ][6738] {'ret': True}
2019-05-09 00:33:33,652 [salt.state       :1951][INFO    ][6738] Completed state [maas.wait_for_machine_status] at time 00:33:33.652171 duration_in_ms=191598.169
2019-05-09 00:33:33,656 [salt.minion      :1711][INFO    ][6738] Returning information for job: 20190509003016965274
2019-05-09 00:33:34,315 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509003334299003
2019-05-09 00:33:34,337 [salt.minion      :1432][INFO    ][8052] Starting a new job with PID 8052
2019-05-09 00:33:38,070 [salt.state       :915 ][INFO    ][8052] Loading fresh modules for state activity
2019-05-09 00:33:38,125 [salt.fileclient  :1219][INFO    ][8052] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-09 00:33:38,221 [salt.state       :1780][INFO    ][8052] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:33:38.220936
2019-05-09 00:33:38,221 [salt.state       :1813][INFO    ][8052] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 00:33:38,223 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8052] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 00:33:39,815 [salt.state       :300 ][INFO    ][8052] {'pid': 8109, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 00:33:39,816 [salt.state       :1951][INFO    ][8052] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:33:39.816374 duration_in_ms=1595.438
2019-05-09 00:33:39,819 [salt.state       :1780][INFO    ][8052] Running state [maas_machines_storage_cmp002_lvm] at time 00:33:39.819719
2019-05-09 00:33:39,820 [salt.state       :1813][INFO    ][8052] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-09 00:33:41,257 [salt.loaded.ext.module.maasng:610 ][INFO    ][8052] gqba4h
2019-05-09 00:33:41,258 [salt.loaded.ext.module.maasng:626 ][INFO    ][8052] sda
2019-05-09 00:33:42,007 [salt.loaded.ext.module.maasng:361 ][INFO    ][8052] gqba4h
2019-05-09 00:33:42,145 [salt.loaded.ext.module.maasng:367 ][INFO    ][8052] [{u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'76eff9bc-10bc-4e57-9803-e2b0ed64e607', u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/blockdevices/4/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gqba4h', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'8e9c86b9-d798-4d22-afd1-99402ee14194', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 5, u'size': 2397992648704}], u'uuid': None, u'id': 4, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'gqba4h', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/blockdevices/4/', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'468a5187-a993-4519-a841-c6f3ea88fb25', u'id': 10, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'gqba4h', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'fff71428-945a-4655-aa47-546f29c2bcf5', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/blockdevices/10/', u'serial': None, u'size': 2397988454400}]
2019-05-09 00:33:42,146 [salt.loaded.ext.module.maasng:632 ][INFO    ][8052] vgroot
2019-05-09 00:33:42,146 [salt.loaded.ext.module.maasng:635 ][INFO    ][8052] lvroot
2019-05-09 00:33:42,146 [salt.loaded.ext.module.maasng:639 ][INFO    ][8052] 107374182400
2019-05-09 00:33:42,864 [salt.loaded.ext.module.maasng:645 ][INFO    ][8052] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'status_action': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'583518f4-f2c2-4b18-a5b6-5669dd018bd1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gqba4h', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0533ac62-4bdf-4cca-911d-ab206c3df91b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/blockdevices/4/partition/7', u'type': u'partition', u'id': 7, u'device_id': 4}], u'id': 4, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'gqba4h', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/blockdevices/4/'}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 4, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'gqba4h', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'6be5d141-aeae-4207-bb16-ad557b26ec49', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'gqba4h', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fcf61235-41e4-4295-aeec-ff0c8b784230', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/blockdevices/12/'}], u'blockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'583518f4-f2c2-4b18-a5b6-5669dd018bd1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gqba4h', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0533ac62-4bdf-4cca-911d-ab206c3df91b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/blockdevices/4/partition/7', u'type': u'partition', u'id': 7, u'device_id': 4}], u'filesystem': None, u'id': 4, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'gqba4h', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/blockdevices/4/'}, {u'size': 107374182400, u'uuid': u'6be5d141-aeae-4207-bb16-ad557b26ec49', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'fcf61235-41e4-4295-aeec-ff0c8b784230', u'fstype': u'ext4'}, u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'gqba4h', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/blockdevices/12/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'on', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'gqba4h', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'583518f4-f2c2-4b18-a5b6-5669dd018bd1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gqba4h', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0533ac62-4bdf-4cca-911d-ab206c3df91b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/blockdevices/4/partition/7', u'type': u'partition', u'id': 7, u'device_id': 4}], u'id': 4, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'gqba4h', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/blockdevices/4/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'ip_addresses': [u'192.168.11.40'], u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 35, u'mode': u'dhcp'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'gqba4h', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/interfaces/5/'}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 35, u'mode': u'dhcp'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'gqba4h', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/interfaces/5/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'id': 36, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 18, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'gqba4h', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/interfaces/18/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'id': 37, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 19, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'gqba4h', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/interfaces/19/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'id': 38, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 20, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'gqba4h', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/gqba4h/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/gqba4h/'}
2019-05-09 00:33:42,866 [salt.state       :300 ][INFO    ][8052] {'new': {'storage_layout': 'lvm'}}
2019-05-09 00:33:42,866 [salt.state       :1951][INFO    ][8052] Completed state [maas_machines_storage_cmp002_lvm] at time 00:33:42.866857 duration_in_ms=3047.137
2019-05-09 00:33:42,867 [salt.state       :1780][INFO    ][8052] Running state [maas_machines_storage_cmp001_lvm] at time 00:33:42.867418
2019-05-09 00:33:42,867 [salt.state       :1813][INFO    ][8052] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-09 00:33:44,464 [salt.loaded.ext.module.maasng:610 ][INFO    ][8052] 6nynfb
2019-05-09 00:33:44,464 [salt.loaded.ext.module.maasng:626 ][INFO    ][8052] sda
2019-05-09 00:33:45,053 [salt.loaded.ext.module.maasng:361 ][INFO    ][8052] 6nynfb
2019-05-09 00:33:45,186 [salt.loaded.ext.module.maasng:367 ][INFO    ][8052] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'0b95566b-5851-49db-a27f-d6517a09a12c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6nynfb', u'device_id': 1, u'filesystem': {u'uuid': u'edf3fb49-8668-49ed-8b37-98d70cbff62e', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/blockdevices/1/partition/1'}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6nynfb', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160, u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/blockdevices/1/'}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'uuid': u'ac3df001-9f5e-4b3c-abdc-75544b53a3f3', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'314bd71f-57fe-43a1-9af2-9b6315eb5296', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6nynfb', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 2397988454400, u'type': u'virtual', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/blockdevices/6/'}]
2019-05-09 00:33:45,187 [salt.loaded.ext.module.maasng:632 ][INFO    ][8052] vgroot
2019-05-09 00:33:45,187 [salt.loaded.ext.module.maasng:635 ][INFO    ][8052] lvroot
2019-05-09 00:33:45,187 [salt.loaded.ext.module.maasng:639 ][INFO    ][8052] 107374182400
2019-05-09 00:33:45,896 [salt.loaded.ext.module.maasng:645 ][INFO    ][8052] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39'], u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'6nynfb', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'2e59bf8c-1af3-4fa5-a152-1318faa3ae35', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6nynfb', u'device_id': 1, u'filesystem': {u'uuid': u'9efbc6b6-a514-467b-a8ef-475fa653221f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'filesystem': None, u'id': 1, u'name': u'sda', u'system_id': u'6nynfb', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/blockdevices/1/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6nynfb', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 8, u'node_type_name': u'Machine', u'current_testing_result_id': 9, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'e93bc6a6-cb08-4c70-80a2-e057f8c22ce3', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'6507bbdb-d89e-4526-8ed2-630c99065bf0', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id': 13, u'name': u'vgroot-lvroot', u'system_id': u'6nynfb', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/blockdevices/13/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 16}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'6nynfb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/interfaces/7/'}, u'boot_disk': {u'size': 2397998940160, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'2e59bf8c-1af3-4fa5-a152-1318faa3ae35', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6nynfb', u'device_id': 1, u'filesystem': {u'uuid': u'9efbc6b6-a514-467b-a8ef-475fa653221f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'filesystem': None, u'id': 1, u'name': u'sda', u'system_id': u'6nynfb', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/blockdevices/1/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 16}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'6nynfb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/interfaces/7/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 9, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'6nynfb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/interfaces/9/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 10, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'6nynfb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/interfaces/10/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'6nynfb', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/interfaces/11/'}], u'address_ttl': None, u'blockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'2e59bf8c-1af3-4fa5-a152-1318faa3ae35', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6nynfb', u'device_id': 1, u'filesystem': {u'uuid': u'9efbc6b6-a514-467b-a8ef-475fa653221f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/blockdevices/1/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'filesystem': None, u'id': 1, u'name': u'sda', u'system_id': u'6nynfb', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/blockdevices/1/'}, {u'size': 107374182400, u'uuid': u'e93bc6a6-cb08-4c70-80a2-e057f8c22ce3', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'6507bbdb-d89e-4526-8ed2-630c99065bf0', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id': 13, u'name': u'vgroot-lvroot', u'system_id': u'6nynfb', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6nynfb/blockdevices/13/'}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/6nynfb/'}
2019-05-09 00:33:45,899 [salt.state       :300 ][INFO    ][8052] {'new': {'storage_layout': 'lvm'}}
2019-05-09 00:33:45,899 [salt.state       :1951][INFO    ][8052] Completed state [maas_machines_storage_cmp001_lvm] at time 00:33:45.899602 duration_in_ms=3032.183
2019-05-09 00:33:45,903 [salt.minion      :1711][INFO    ][8052] Returning information for job: 20190509003334299003
2019-05-09 00:33:46,474 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509003346461398
2019-05-09 00:33:46,494 [salt.minion      :1432][INFO    ][8153] Starting a new job with PID 8153
2019-05-09 00:33:47,196 [salt.state       :915 ][INFO    ][8153] Loading fresh modules for state activity
2019-05-09 00:33:47,252 [salt.fileclient  :1219][INFO    ][8153] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-09 00:33:47,292 [salt.state       :1780][INFO    ][8153] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:33:47.292244
2019-05-09 00:33:47,292 [salt.state       :1813][INFO    ][8153] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 00:33:47,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8153] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 00:33:48,745 [salt.state       :300 ][INFO    ][8153] {'pid': 8160, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 00:33:48,746 [salt.state       :1951][INFO    ][8153] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:33:48.746165 duration_in_ms=1453.922
2019-05-09 00:33:48,747 [salt.state       :1780][INFO    ][8153] Running state [maas.deploy_machines] at time 00:33:48.747365
2019-05-09 00:33:48,747 [salt.state       :1813][INFO    ][8153] Executing state module.run for [maas.deploy_machines]
2019-05-09 00:33:48,747 [salt.utils.decorators:613 ][WARNING ][8153] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:33:49,428 [salt.loaded.ext.module.maas:684 ][INFO    ][8153] deploymachines hwe_kernel=hwe-16.04 system_id=y3ypmb distro_series=xenial
2019-05-09 00:33:52,393 [salt.loaded.ext.module.maas:684 ][INFO    ][8153] deploymachines hwe_kernel=hwe-16.04 system_id=gqba4h distro_series=xenial
2019-05-09 00:33:54,955 [salt.loaded.ext.module.maas:684 ][INFO    ][8153] deploymachines hwe_kernel=hwe-16.04 system_id=admr6s distro_series=xenial
2019-05-09 00:33:57,382 [salt.loaded.ext.module.maas:684 ][INFO    ][8153] deploymachines hwe_kernel=hwe-16.04 system_id=6nynfb distro_series=xenial
2019-05-09 00:34:00,096 [salt.loaded.ext.module.maas:684 ][INFO    ][8153] deploymachines hwe_kernel=hwe-16.04 system_id=hqp8pk distro_series=xenial
2019-05-09 00:34:01,575 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003401565420
2019-05-09 00:34:01,596 [salt.minion      :1432][INFO    ][8418] Starting a new job with PID 8418
2019-05-09 00:34:01,619 [salt.minion      :1711][INFO    ][8418] Returning information for job: 20190509003401565420
2019-05-09 00:34:02,576 [salt.state       :300 ][INFO    ][8153] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']}}
2019-05-09 00:34:02,576 [salt.state       :1951][INFO    ][8153] Completed state [maas.deploy_machines] at time 00:34:02.576539 duration_in_ms=13829.173
2019-05-09 00:34:02,580 [salt.minion      :1711][INFO    ][8153] Returning information for job: 20190509003346461398
2019-05-09 00:34:03,175 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509003403162181
2019-05-09 00:34:03,197 [salt.minion      :1432][INFO    ][8435] Starting a new job with PID 8435
2019-05-09 00:34:06,996 [salt.state       :915 ][INFO    ][8435] Loading fresh modules for state activity
2019-05-09 00:34:07,044 [salt.fileclient  :1219][INFO    ][8435] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-09 00:34:07,089 [salt.state       :1780][INFO    ][8435] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:34:07.089447
2019-05-09 00:34:07,089 [salt.state       :1813][INFO    ][8435] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 00:34:07,092 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8435] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 00:34:08,530 [salt.state       :300 ][INFO    ][8435] {'pid': 8450, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 00:34:08,531 [salt.state       :1951][INFO    ][8435] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:34:08.531785 duration_in_ms=1442.337
2019-05-09 00:34:08,534 [salt.state       :1780][INFO    ][8435] Running state [maas.wait_for_machine_status] at time 00:34:08.534340
2019-05-09 00:34:08,534 [salt.state       :1813][INFO    ][8435] Executing state module.run for [maas.wait_for_machine_status]
2019-05-09 00:34:08,535 [salt.utils.decorators:613 ][WARNING ][8435] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:34:11,748 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2246.79819679s left)
2019-05-09 00:34:18,284 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003418269048
2019-05-09 00:34:18,304 [salt.minion      :1432][INFO    ][8462] Starting a new job with PID 8462
2019-05-09 00:34:18,326 [salt.minion      :1711][INFO    ][8462] Returning information for job: 20190509003418269048
2019-05-09 00:34:45,460 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2213.08638692s left)
2019-05-09 00:34:48,327 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003448313349
2019-05-09 00:34:48,350 [salt.minion      :1432][INFO    ][8519] Starting a new job with PID 8519
2019-05-09 00:34:48,373 [salt.minion      :1711][INFO    ][8519] Returning information for job: 20190509003448313349
2019-05-09 00:35:18,376 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003518363784
2019-05-09 00:35:18,399 [salt.minion      :1432][INFO    ][8539] Starting a new job with PID 8539
2019-05-09 00:35:18,423 [salt.minion      :1711][INFO    ][8539] Returning information for job: 20190509003518363784
2019-05-09 00:35:18,845 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2179.70126581s left)
2019-05-09 00:35:48,429 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003548416424
2019-05-09 00:35:48,452 [salt.minion      :1432][INFO    ][8706] Starting a new job with PID 8706
2019-05-09 00:35:48,475 [salt.minion      :1711][INFO    ][8706] Returning information for job: 20190509003548416424
2019-05-09 00:35:52,395 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2146.15090489s left)
2019-05-09 00:36:18,482 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003618468999
2019-05-09 00:36:18,504 [salt.minion      :1432][INFO    ][8846] Starting a new job with PID 8846
2019-05-09 00:36:18,528 [salt.minion      :1711][INFO    ][8846] Returning information for job: 20190509003618468999
2019-05-09 00:36:26,094 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2112.4524858s left)
2019-05-09 00:36:48,535 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003648522422
2019-05-09 00:36:48,559 [salt.minion      :1432][INFO    ][9598] Starting a new job with PID 9598
2019-05-09 00:36:48,584 [salt.minion      :1711][INFO    ][9598] Returning information for job: 20190509003648522422
2019-05-09 00:36:59,462 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2079.0844748s left)
2019-05-09 00:37:18,596 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003718581775
2019-05-09 00:37:18,619 [salt.minion      :1432][INFO    ][9618] Starting a new job with PID 9618
2019-05-09 00:37:18,643 [salt.minion      :1711][INFO    ][9618] Returning information for job: 20190509003718581775
2019-05-09 00:37:32,724 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2045.82174301s left)
2019-05-09 00:37:48,661 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003748648405
2019-05-09 00:37:48,683 [salt.minion      :1432][INFO    ][9805] Starting a new job with PID 9805
2019-05-09 00:37:48,709 [salt.minion      :1711][INFO    ][9805] Returning information for job: 20190509003748648405
2019-05-09 00:38:06,450 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2012.09588289s left)
2019-05-09 00:38:18,726 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003818712050
2019-05-09 00:38:18,748 [salt.minion      :1432][INFO    ][9833] Starting a new job with PID 9833
2019-05-09 00:38:18,770 [salt.minion      :1711][INFO    ][9833] Returning information for job: 20190509003818712050
2019-05-09 00:38:38,924 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1979.62236094s left)
2019-05-09 00:38:48,796 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003848780087
2019-05-09 00:38:48,818 [salt.minion      :1432][INFO    ][10415] Starting a new job with PID 10415
2019-05-09 00:38:48,839 [salt.minion      :1711][INFO    ][10415] Returning information for job: 20190509003848780087
2019-05-09 00:39:12,499 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1946.04726791s left)
2019-05-09 00:39:18,866 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003918854573
2019-05-09 00:39:18,888 [salt.minion      :1432][INFO    ][10451] Starting a new job with PID 10451
2019-05-09 00:39:18,911 [salt.minion      :1711][INFO    ][10451] Returning information for job: 20190509003918854573
2019-05-09 00:39:46,087 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1912.45928001s left)
2019-05-09 00:39:48,938 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509003948925018
2019-05-09 00:39:48,960 [salt.minion      :1432][INFO    ][10871] Starting a new job with PID 10871
2019-05-09 00:39:48,985 [salt.minion      :1711][INFO    ][10871] Returning information for job: 20190509003948925018
2019-05-09 00:40:19,021 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004019009916
2019-05-09 00:40:19,042 [salt.minion      :1432][INFO    ][10890] Starting a new job with PID 10890
2019-05-09 00:40:19,065 [salt.minion      :1711][INFO    ][10890] Returning information for job: 20190509004019009916
2019-05-09 00:40:19,636 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1878.90976095s left)
2019-05-09 00:40:49,104 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004049091727
2019-05-09 00:40:49,126 [salt.minion      :1432][INFO    ][10940] Starting a new job with PID 10940
2019-05-09 00:40:49,150 [salt.minion      :1711][INFO    ][10940] Returning information for job: 20190509004049091727
2019-05-09 00:40:53,397 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1845.1492939s left)
2019-05-09 00:41:19,203 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004119190154
2019-05-09 00:41:19,224 [salt.minion      :1432][INFO    ][11063] Starting a new job with PID 11063
2019-05-09 00:41:19,248 [salt.minion      :1711][INFO    ][11063] Returning information for job: 20190509004119190154
2019-05-09 00:41:26,822 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'ctl01']
sleep for:30s Timeout:2250s (1811.72396779s left)
2019-05-09 00:41:49,341 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004149329122
2019-05-09 00:41:49,357 [salt.minion      :1432][INFO    ][11530] Starting a new job with PID 11530
2019-05-09 00:41:49,378 [salt.minion      :1711][INFO    ][11530] Returning information for job: 20190509004149329122
2019-05-09 00:42:00,308 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1778.23811197s left)
2019-05-09 00:42:19,435 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004219422998
2019-05-09 00:42:19,458 [salt.minion      :1432][INFO    ][11547] Starting a new job with PID 11547
2019-05-09 00:42:19,481 [salt.minion      :1711][INFO    ][11547] Returning information for job: 20190509004219422998
2019-05-09 00:42:33,692 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1744.85419679s left)
2019-05-09 00:42:49,549 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004249536460
2019-05-09 00:42:49,571 [salt.minion      :1432][INFO    ][11749] Starting a new job with PID 11749
2019-05-09 00:42:49,594 [salt.minion      :1711][INFO    ][11749] Returning information for job: 20190509004249536460
2019-05-09 00:43:07,376 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1711.16964984s left)
2019-05-09 00:43:19,670 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004319655572
2019-05-09 00:43:19,692 [salt.minion      :1432][INFO    ][11767] Starting a new job with PID 11767
2019-05-09 00:43:19,716 [salt.minion      :1711][INFO    ][11767] Returning information for job: 20190509004319655572
2019-05-09 00:43:40,902 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1677.64354992s left)
2019-05-09 00:43:49,796 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004349783716
2019-05-09 00:43:49,819 [salt.minion      :1432][INFO    ][11827] Starting a new job with PID 11827
2019-05-09 00:43:49,843 [salt.minion      :1711][INFO    ][11827] Returning information for job: 20190509004349783716
2019-05-09 00:44:14,231 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1644.31521487s left)
2019-05-09 00:44:19,930 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004419917146
2019-05-09 00:44:19,952 [salt.minion      :1432][INFO    ][11846] Starting a new job with PID 11846
2019-05-09 00:44:19,975 [salt.minion      :1711][INFO    ][11846] Returning information for job: 20190509004419917146
2019-05-09 00:44:47,848 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1610.69882894s left)
2019-05-09 00:44:50,077 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004450062097
2019-05-09 00:44:50,100 [salt.minion      :1432][INFO    ][11903] Starting a new job with PID 11903
2019-05-09 00:44:50,122 [salt.minion      :1711][INFO    ][11903] Returning information for job: 20190509004450062097
2019-05-09 00:45:20,235 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004520219898
2019-05-09 00:45:20,256 [salt.minion      :1432][INFO    ][11924] Starting a new job with PID 11924
2019-05-09 00:45:20,281 [salt.minion      :1711][INFO    ][11924] Returning information for job: 20190509004520219898
2019-05-09 00:45:21,423 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1577.1228478s left)
2019-05-09 00:45:50,399 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004550383735
2019-05-09 00:45:50,422 [salt.minion      :1432][INFO    ][11982] Starting a new job with PID 11982
2019-05-09 00:45:50,446 [salt.minion      :1711][INFO    ][11982] Returning information for job: 20190509004550383735
2019-05-09 00:45:55,018 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1543.52753186s left)
2019-05-09 00:46:20,578 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004620562891
2019-05-09 00:46:20,601 [salt.minion      :1432][INFO    ][12009] Starting a new job with PID 12009
2019-05-09 00:46:20,625 [salt.minion      :1711][INFO    ][12009] Returning information for job: 20190509004620562891
2019-05-09 00:46:28,320 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1510.22606587s left)
2019-05-09 00:46:50,757 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004650744408
2019-05-09 00:46:50,779 [salt.minion      :1432][INFO    ][12068] Starting a new job with PID 12068
2019-05-09 00:46:50,803 [salt.minion      :1711][INFO    ][12068] Returning information for job: 20190509004650744408
2019-05-09 00:47:01,950 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1476.59638095s left)
2019-05-09 00:47:20,945 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004720933381
2019-05-09 00:47:20,968 [salt.minion      :1432][INFO    ][12088] Starting a new job with PID 12088
2019-05-09 00:47:20,990 [salt.minion      :1711][INFO    ][12088] Returning information for job: 20190509004720933381
2019-05-09 00:47:35,435 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1443.11135793s left)
2019-05-09 00:47:51,143 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004751129814
2019-05-09 00:47:51,166 [salt.minion      :1432][INFO    ][12148] Starting a new job with PID 12148
2019-05-09 00:47:51,189 [salt.minion      :1711][INFO    ][12148] Returning information for job: 20190509004751129814
2019-05-09 00:48:08,769 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1409.77736402s left)
2019-05-09 00:48:21,353 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004821339239
2019-05-09 00:48:21,376 [salt.minion      :1432][INFO    ][12168] Starting a new job with PID 12168
2019-05-09 00:48:21,399 [salt.minion      :1711][INFO    ][12168] Returning information for job: 20190509004821339239
2019-05-09 00:48:42,297 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1376.24857998s left)
2019-05-09 00:48:51,577 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004851564872
2019-05-09 00:48:51,600 [salt.minion      :1432][INFO    ][12370] Starting a new job with PID 12370
2019-05-09 00:48:51,624 [salt.minion      :1711][INFO    ][12370] Returning information for job: 20190509004851564872
2019-05-09 00:49:15,471 [salt.loaded.ext.module.maas:1023][INFO    ][8435] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1343.07508492s left)
2019-05-09 00:49:21,596 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509004921583720
2019-05-09 00:49:21,619 [salt.minion      :1432][INFO    ][12396] Starting a new job with PID 12396
2019-05-09 00:49:21,644 [salt.minion      :1711][INFO    ][12396] Returning information for job: 20190509004921583720
2019-05-09 00:49:46,226 [salt.loaded.ext.module.maas:993 ][INFO    ][8435] Machine y3ypmb mark broken
2019-05-09 00:49:46,951 [salt.loaded.ext.module.maas:996 ][INFO    ][8435] Machine y3ypmb mark fixed
2019-05-09 00:49:48,016 [salt.loaded.ext.module.maas:684 ][INFO    ][8435] deploymachines hwe_kernel=hwe-16.04 system_id=y3ypmb distro_series=xenial
2019-05-09 00:49:50,714 [salt.loaded.ext.module.maas:160 ][ERROR   ][8435] Failed for object gtw01 reason Unable to change power state to 'cycle' for node gtw01: another action is already in progress for that node.
2019-05-09 00:49:50,716 [salt.state       :302 ][ERROR   ][8435] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'odl01', 'cmp001', 'ctl01'], 'errors': {'gtw01': "Unable to change power state to 'cycle' for node gtw01: another action is already in progress for that node."}, 'success': []}
2019-05-09 00:49:50,716 [salt.state       :1951][INFO    ][8435] Completed state [maas.wait_for_machine_status] at time 00:49:50.716422 duration_in_ms=942182.076
2019-05-09 00:49:50,723 [salt.minion      :1711][INFO    ][8435] Returning information for job: 20190509003403162181
2019-05-09 00:50:01,421 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command pillar.get with jid 20190509005001408814
2019-05-09 00:50:01,439 [salt.minion      :1432][INFO    ][12534] Starting a new job with PID 12534
2019-05-09 00:50:01,447 [salt.minion      :1711][INFO    ][12534] Returning information for job: 20190509005001408814
2019-05-09 00:50:01,992 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command service.status with jid 20190509005001978797
2019-05-09 00:50:02,011 [salt.minion      :1432][INFO    ][12542] Starting a new job with PID 12542
2019-05-09 00:50:02,354 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12542] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-09 00:50:02,390 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12542] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-09 00:50:02,406 [salt.minion      :1711][INFO    ][12542] Returning information for job: 20190509005001978797
2019-05-09 00:50:02,957 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509005002944904
2019-05-09 00:50:02,979 [salt.minion      :1432][INFO    ][12555] Starting a new job with PID 12555
2019-05-09 00:50:06,715 [salt.state       :915 ][INFO    ][12555] Loading fresh modules for state activity
2019-05-09 00:50:07,169 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command 'salt-minion --version' in directory '/root'
2019-05-09 00:50:07,529 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command 'salt-minion --version' in directory '/root'
2019-05-09 00:50:08,364 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command 'salt-minion --version' in directory '/root'
2019-05-09 00:50:08,743 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command 'salt-minion --version' in directory '/root'
2019-05-09 00:50:10,189 [salt.state       :1780][INFO    ][12555] Running state [salt-minion] at time 00:50:10.189803
2019-05-09 00:50:10,190 [salt.state       :1813][INFO    ][12555] Executing state pkg.installed for [salt-minion]
2019-05-09 00:50:10,190 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-09 00:50:10,286 [salt.state       :300 ][INFO    ][12555] All specified packages are already installed
2019-05-09 00:50:10,286 [salt.state       :1951][INFO    ][12555] Completed state [salt-minion] at time 00:50:10.286896 duration_in_ms=97.093
2019-05-09 00:50:10,287 [salt.state       :1780][INFO    ][12555] Running state [salt_minion_dependency_packages] at time 00:50:10.287264
2019-05-09 00:50:10,287 [salt.state       :1813][INFO    ][12555] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-09 00:50:10,294 [salt.state       :300 ][INFO    ][12555] All specified packages are already installed
2019-05-09 00:50:10,294 [salt.state       :1951][INFO    ][12555] Completed state [salt_minion_dependency_packages] at time 00:50:10.294876 duration_in_ms=7.611
2019-05-09 00:50:10,298 [salt.state       :1780][INFO    ][12555] Running state [/etc/salt/minion.d/minion.conf] at time 00:50:10.298313
2019-05-09 00:50:10,298 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-09 00:50:10,515 [salt.state       :300 ][INFO    ][12555] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-09 00:50:10,516 [salt.state       :1951][INFO    ][12555] Completed state [/etc/salt/minion.d/minion.conf] at time 00:50:10.516169 duration_in_ms=217.855
2019-05-09 00:50:10,519 [salt.state       :1780][INFO    ][12555] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 00:50:10.519412
2019-05-09 00:50:10,519 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-09 00:50:10,530 [salt.state       :300 ][INFO    ][12555] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-09 00:50:10,531 [salt.state       :1951][INFO    ][12555] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 00:50:10.531137 duration_in_ms=11.725
2019-05-09 00:50:10,532 [salt.state       :1780][INFO    ][12555] Running state [salt-minion] at time 00:50:10.532133
2019-05-09 00:50:10,532 [salt.state       :1813][INFO    ][12555] Executing state service.running for [salt-minion]
2019-05-09 00:50:10,533 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-09 00:50:10,571 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-09 00:50:10,589 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-09 00:50:10,610 [salt.state       :300 ][INFO    ][12555] The service salt-minion is already running
2019-05-09 00:50:10,611 [salt.state       :1951][INFO    ][12555] Completed state [salt-minion] at time 00:50:10.611377 duration_in_ms=79.243
2019-05-09 00:50:10,613 [salt.state       :1780][INFO    ][12555] Running state [/etc/salt/grains.d] at time 00:50:10.613921
2019-05-09 00:50:10,614 [salt.state       :1813][INFO    ][12555] Executing state file.directory for [/etc/salt/grains.d]
2019-05-09 00:50:10,615 [salt.state       :300 ][INFO    ][12555] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-09 00:50:10,616 [salt.state       :1951][INFO    ][12555] Completed state [/etc/salt/grains.d] at time 00:50:10.616285 duration_in_ms=2.363
2019-05-09 00:50:10,617 [salt.state       :1780][INFO    ][12555] Running state [/etc/salt/grains] at time 00:50:10.617387
2019-05-09 00:50:10,617 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/salt/grains]
2019-05-09 00:50:10,618 [salt.state       :300 ][INFO    ][12555] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-09 00:50:10,619 [salt.state       :1951][INFO    ][12555] Completed state [/etc/salt/grains] at time 00:50:10.619014 duration_in_ms=1.627
2019-05-09 00:50:10,619 [salt.state       :1780][INFO    ][12555] Running state [/etc/salt/grains.d/placeholder] at time 00:50:10.619730
2019-05-09 00:50:10,620 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-09 00:50:10,620 [salt.state       :300 ][INFO    ][12555] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-09 00:50:10,621 [salt.state       :1951][INFO    ][12555] Completed state [/etc/salt/grains.d/placeholder] at time 00:50:10.621233 duration_in_ms=1.503
2019-05-09 00:50:10,622 [salt.state       :1780][INFO    ][12555] Running state [/etc/salt/grains.d/sphinx] at time 00:50:10.621953
2019-05-09 00:50:10,622 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-09 00:50:10,635 [salt.state       :300 ][INFO    ][12555] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-09 00:50:10,636 [salt.state       :1951][INFO    ][12555] Completed state [/etc/salt/grains.d/sphinx] at time 00:50:10.636209 duration_in_ms=14.256
2019-05-09 00:50:10,639 [salt.state       :1780][INFO    ][12555] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 00:50:10.639604
2019-05-09 00:50:10,640 [salt.state       :1813][INFO    ][12555] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-09 00:50:10,640 [salt.state       :300 ][INFO    ][12555] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-09 00:50:10,640 [salt.state       :1951][INFO    ][12555] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 00:50:10.640880 duration_in_ms=1.277
2019-05-09 00:50:10,641 [salt.state       :1780][INFO    ][12555] Running state [/etc/salt/grains.d/dns_records] at time 00:50:10.641611
2019-05-09 00:50:10,642 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-09 00:50:10,653 [salt.state       :300 ][INFO    ][12555] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-09 00:50:10,654 [salt.state       :1951][INFO    ][12555] Completed state [/etc/salt/grains.d/dns_records] at time 00:50:10.654006 duration_in_ms=12.394
2019-05-09 00:50:10,655 [salt.state       :1780][INFO    ][12555] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 00:50:10.655337
2019-05-09 00:50:10,655 [salt.state       :1813][INFO    ][12555] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-09 00:50:10,656 [salt.state       :300 ][INFO    ][12555] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-09 00:50:10,656 [salt.state       :1951][INFO    ][12555] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 00:50:10.656557 duration_in_ms=1.22
2019-05-09 00:50:10,657 [salt.state       :1780][INFO    ][12555] Running state [/etc/salt/grains.d/salt] at time 00:50:10.657223
2019-05-09 00:50:10,657 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-09 00:50:10,665 [salt.state       :300 ][INFO    ][12555] File /etc/salt/grains.d/salt is in the correct state
2019-05-09 00:50:10,665 [salt.state       :1951][INFO    ][12555] Completed state [/etc/salt/grains.d/salt] at time 00:50:10.665922 duration_in_ms=8.699
2019-05-09 00:50:10,667 [salt.state       :1780][INFO    ][12555] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 00:50:10.667182
2019-05-09 00:50:10,667 [salt.state       :1813][INFO    ][12555] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-09 00:50:10,668 [salt.state       :300 ][INFO    ][12555] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-09 00:50:10,668 [salt.state       :1951][INFO    ][12555] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 00:50:10.668359 duration_in_ms=1.177
2019-05-09 00:50:10,671 [salt.state       :1780][INFO    ][12555] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 00:50:10.671103
2019-05-09 00:50:10,671 [salt.state       :1813][INFO    ][12555] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-09 00:50:10,672 [salt.state       :300 ][INFO    ][12555] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-09 00:50:10,672 [salt.state       :1951][INFO    ][12555] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 00:50:10.672299 duration_in_ms=1.196
2019-05-09 00:50:10,673 [salt.state       :1780][INFO    ][12555] Running state [mine.update] at time 00:50:10.673273
2019-05-09 00:50:10,673 [salt.state       :1813][INFO    ][12555] Executing state module.wait for [mine.update]
2019-05-09 00:50:10,674 [salt.state       :300 ][INFO    ][12555] No changes made for mine.update
2019-05-09 00:50:10,674 [salt.state       :1951][INFO    ][12555] Completed state [mine.update] at time 00:50:10.674364 duration_in_ms=1.092
2019-05-09 00:50:10,674 [salt.state       :1780][INFO    ][12555] Running state [ca-certificates] at time 00:50:10.674727
2019-05-09 00:50:10,675 [salt.state       :1813][INFO    ][12555] Executing state pkg.installed for [ca-certificates]
2019-05-09 00:50:10,685 [salt.state       :300 ][INFO    ][12555] All specified packages are already installed
2019-05-09 00:50:10,685 [salt.state       :1951][INFO    ][12555] Completed state [ca-certificates] at time 00:50:10.685559 duration_in_ms=10.832
2019-05-09 00:50:10,686 [salt.state       :1780][INFO    ][12555] Running state [update-ca-certificates] at time 00:50:10.686884
2019-05-09 00:50:10,687 [salt.state       :1813][INFO    ][12555] Executing state cmd.wait for [update-ca-certificates]
2019-05-09 00:50:10,687 [salt.state       :300 ][INFO    ][12555] No changes made for update-ca-certificates
2019-05-09 00:50:10,688 [salt.state       :1951][INFO    ][12555] Completed state [update-ca-certificates] at time 00:50:10.687988 duration_in_ms=1.105
2019-05-09 00:50:10,688 [salt.state       :1780][INFO    ][12555] Running state [iptables] at time 00:50:10.688295
2019-05-09 00:50:10,688 [salt.state       :1813][INFO    ][12555] Executing state pkg.installed for [iptables]
2019-05-09 00:50:10,697 [salt.state       :300 ][INFO    ][12555] All specified packages are already installed
2019-05-09 00:50:10,697 [salt.state       :1951][INFO    ][12555] Completed state [iptables] at time 00:50:10.697616 duration_in_ms=9.321
2019-05-09 00:50:10,697 [salt.state       :1780][INFO    ][12555] Running state [iptables-persistent] at time 00:50:10.697928
2019-05-09 00:50:10,698 [salt.state       :1813][INFO    ][12555] Executing state pkg.installed for [iptables-persistent]
2019-05-09 00:50:10,706 [salt.state       :300 ][INFO    ][12555] All specified packages are already installed
2019-05-09 00:50:10,707 [salt.state       :1951][INFO    ][12555] Completed state [iptables-persistent] at time 00:50:10.707000 duration_in_ms=9.071
2019-05-09 00:50:10,708 [salt.state       :1780][INFO    ][12555] Running state [iptables_modules_v4_load] at time 00:50:10.708248
2019-05-09 00:50:10,708 [salt.state       :1813][INFO    ][12555] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-09 00:50:10,709 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command 'lsmod' in directory '/root'
2019-05-09 00:50:10,735 [salt.state       :300 ][INFO    ][12555] Kernel modules iptable_filter, ip_tables are already present
2019-05-09 00:50:10,735 [salt.state       :1951][INFO    ][12555] Completed state [iptables_modules_v4_load] at time 00:50:10.735506 duration_in_ms=27.257
2019-05-09 00:50:10,736 [salt.state       :1780][INFO    ][12555] Running state [/etc/iptables/rules.v4] at time 00:50:10.736276
2019-05-09 00:50:10,736 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-09 00:50:10,839 [salt.state       :300 ][INFO    ][12555] File /etc/iptables/rules.v4 is in the correct state
2019-05-09 00:50:10,839 [salt.state       :1951][INFO    ][12555] Completed state [/etc/iptables/rules.v4] at time 00:50:10.839852 duration_in_ms=103.576
2019-05-09 00:50:10,840 [salt.state       :1780][INFO    ][12555] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 00:50:10.840929
2019-05-09 00:50:10,841 [salt.state       :1813][INFO    ][12555] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-09 00:50:10,841 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-09 00:50:10,863 [salt.state       :300 ][INFO    ][12555] onlyif execution failed
2019-05-09 00:50:10,864 [salt.state       :1951][INFO    ][12555] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 00:50:10.864264 duration_in_ms=23.335
2019-05-09 00:50:10,865 [salt.state       :1780][INFO    ][12555] Running state [netfilter-persistent] at time 00:50:10.865249
2019-05-09 00:50:10,865 [salt.state       :1813][INFO    ][12555] Executing state service.running for [netfilter-persistent]
2019-05-09 00:50:10,866 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-09 00:50:10,887 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-09 00:50:10,906 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-09 00:50:10,925 [salt.state       :300 ][INFO    ][12555] The service netfilter-persistent is already running
2019-05-09 00:50:10,925 [salt.state       :1951][INFO    ][12555] Completed state [netfilter-persistent] at time 00:50:10.925710 duration_in_ms=60.459
2019-05-09 00:50:10,927 [salt.state       :1780][INFO    ][12555] Running state [iptables_extra.remove_stale_tables] at time 00:50:10.927215
2019-05-09 00:50:10,927 [salt.state       :1813][INFO    ][12555] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-09 00:50:10,928 [salt.state       :300 ][INFO    ][12555] No changes made for iptables_extra.remove_stale_tables
2019-05-09 00:50:10,928 [salt.state       :1951][INFO    ][12555] Completed state [iptables_extra.remove_stale_tables] at time 00:50:10.928747 duration_in_ms=1.532
2019-05-09 00:50:10,929 [salt.state       :1780][INFO    ][12555] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 00:50:10.929163
2019-05-09 00:50:10,929 [salt.state       :1813][INFO    ][12555] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-09 00:50:10,930 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-09 00:50:10,947 [salt.state       :300 ][INFO    ][12555] onlyif execution failed
2019-05-09 00:50:10,948 [salt.state       :1951][INFO    ][12555] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 00:50:10.948395 duration_in_ms=19.232
2019-05-09 00:50:10,950 [salt.state       :1780][INFO    ][12555] Running state [/etc/iptables/rules.v6] at time 00:50:10.950007
2019-05-09 00:50:10,950 [salt.state       :1813][INFO    ][12555] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-09 00:50:10,951 [salt.state       :300 ][INFO    ][12555] File /etc/iptables/rules.v6 is not present
2019-05-09 00:50:10,951 [salt.state       :1951][INFO    ][12555] Completed state [/etc/iptables/rules.v6] at time 00:50:10.951643 duration_in_ms=1.637
2019-05-09 00:50:10,952 [salt.state       :1780][INFO    ][12555] Running state [iptables_extra.flush_all] at time 00:50:10.952786
2019-05-09 00:50:10,953 [salt.state       :1813][INFO    ][12555] Executing state module.wait for [iptables_extra.flush_all]
2019-05-09 00:50:10,953 [salt.state       :300 ][INFO    ][12555] No changes made for iptables_extra.flush_all
2019-05-09 00:50:10,954 [salt.state       :1951][INFO    ][12555] Completed state [iptables_extra.flush_all] at time 00:50:10.954057 duration_in_ms=1.272
2019-05-09 00:50:10,958 [salt.minion      :1711][INFO    ][12555] Returning information for job: 20190509005002944904
2019-05-09 00:50:11,577 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509005011567608
2019-05-09 00:50:11,600 [salt.minion      :1432][INFO    ][12631] Starting a new job with PID 12631
2019-05-09 00:50:12,256 [salt.state       :915 ][INFO    ][12631] Loading fresh modules for state activity
2019-05-09 00:50:12,930 [salt.state       :1780][INFO    ][12631] Running state [maas-rack-controller] at time 00:50:12.929937
2019-05-09 00:50:12,930 [salt.state       :1813][INFO    ][12631] Executing state pkg.installed for [maas-rack-controller]
2019-05-09 00:50:12,930 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12631] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-09 00:50:13,008 [salt.state       :300 ][INFO    ][12631] All specified packages are already installed
2019-05-09 00:50:13,008 [salt.state       :1951][INFO    ][12631] Completed state [maas-rack-controller] at time 00:50:13.008408 duration_in_ms=78.472
2019-05-09 00:50:13,008 [salt.state       :1780][INFO    ][12631] Running state [ipmitool] at time 00:50:13.008670
2019-05-09 00:50:13,008 [salt.state       :1813][INFO    ][12631] Executing state pkg.installed for [ipmitool]
2019-05-09 00:50:13,013 [salt.state       :300 ][INFO    ][12631] All specified packages are already installed
2019-05-09 00:50:13,013 [salt.state       :1951][INFO    ][12631] Completed state [ipmitool] at time 00:50:13.013627 duration_in_ms=4.957
2019-05-09 00:50:13,016 [salt.state       :1780][INFO    ][12631] Running state [/etc/maas/rackd.conf] at time 00:50:13.015966
2019-05-09 00:50:13,016 [salt.state       :1813][INFO    ][12631] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-09 00:50:13,016 [salt.state       :300 ][INFO    ][12631] No changes needed to be made
2019-05-09 00:50:13,017 [salt.state       :1951][INFO    ][12631] Completed state [/etc/maas/rackd.conf] at time 00:50:13.017087 duration_in_ms=1.121
2019-05-09 00:50:13,017 [salt.state       :1780][INFO    ][12631] Running state [/etc/maas/rackd.conf] at time 00:50:13.017254
2019-05-09 00:50:13,017 [salt.state       :1813][INFO    ][12631] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-09 00:50:13,017 [salt.loaded.int.states.file:2298][WARNING ][12631] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-09 00:50:13,018 [salt.state       :300 ][INFO    ][12631] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-09 00:50:13,018 [salt.state       :1951][INFO    ][12631] Completed state [/etc/maas/rackd.conf] at time 00:50:13.018143 duration_in_ms=0.89
2019-05-09 00:50:13,018 [salt.state       :1780][INFO    ][12631] Running state [maas-rackd] at time 00:50:13.018825
2019-05-09 00:50:13,019 [salt.state       :1813][INFO    ][12631] Executing state service.running for [maas-rackd]
2019-05-09 00:50:13,019 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12631] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-09 00:50:13,049 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12631] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-09 00:50:13,064 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12631] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-09 00:50:13,080 [salt.state       :300 ][INFO    ][12631] The service maas-rackd is already running
2019-05-09 00:50:13,080 [salt.state       :1951][INFO    ][12631] Completed state [maas-rackd] at time 00:50:13.080627 duration_in_ms=61.8
2019-05-09 00:50:13,082 [salt.minion      :1711][INFO    ][12631] Returning information for job: 20190509005011567608
2019-05-09 00:50:13,717 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509005013705284
2019-05-09 00:50:13,740 [salt.minion      :1432][INFO    ][12654] Starting a new job with PID 12654
2019-05-09 00:50:14,476 [salt.state       :915 ][INFO    ][12654] Loading fresh modules for state activity
2019-05-09 00:50:15,246 [salt.state       :1780][INFO    ][12654] Running state [maas-region-controller] at time 00:50:15.246399
2019-05-09 00:50:15,246 [salt.state       :1813][INFO    ][12654] Executing state pkg.installed for [maas-region-controller]
2019-05-09 00:50:15,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-09 00:50:15,342 [salt.state       :300 ][INFO    ][12654] All specified packages are already installed
2019-05-09 00:50:15,342 [salt.state       :1951][INFO    ][12654] Completed state [maas-region-controller] at time 00:50:15.342419 duration_in_ms=96.019
2019-05-09 00:50:15,342 [salt.state       :1780][INFO    ][12654] Running state [python-oauth] at time 00:50:15.342790
2019-05-09 00:50:15,343 [salt.state       :1813][INFO    ][12654] Executing state pkg.installed for [python-oauth]
2019-05-09 00:50:15,350 [salt.state       :300 ][INFO    ][12654] All specified packages are already installed
2019-05-09 00:50:15,350 [salt.state       :1951][INFO    ][12654] Completed state [python-oauth] at time 00:50:15.350393 duration_in_ms=7.602
2019-05-09 00:50:15,353 [salt.state       :1780][INFO    ][12654] Running state [/etc/maas/regiond.conf] at time 00:50:15.353827
2019-05-09 00:50:15,354 [salt.state       :1813][INFO    ][12654] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-09 00:50:15,409 [salt.state       :300 ][INFO    ][12654] No changes needed to be made
2019-05-09 00:50:15,409 [salt.state       :1951][INFO    ][12654] Completed state [/etc/maas/regiond.conf] at time 00:50:15.409294 duration_in_ms=55.467
2019-05-09 00:50:15,409 [salt.state       :1780][INFO    ][12654] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 00:50:15.409919
2019-05-09 00:50:15,410 [salt.state       :1813][INFO    ][12654] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-09 00:50:15,486 [salt.state       :300 ][INFO    ][12654] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-09 00:50:15,487 [salt.state       :1951][INFO    ][12654] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 00:50:15.487002 duration_in_ms=77.082
2019-05-09 00:50:15,487 [salt.state       :1780][INFO    ][12654] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:50:15.487729
2019-05-09 00:50:15,488 [salt.state       :1813][INFO    ][12654] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-09 00:50:15,499 [salt.state       :300 ][INFO    ][12654] No changes needed to be made
2019-05-09 00:50:15,500 [salt.state       :1951][INFO    ][12654] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:50:15.499998 duration_in_ms=12.269
2019-05-09 00:50:15,500 [salt.state       :1780][INFO    ][12654] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:50:15.500533
2019-05-09 00:50:15,500 [salt.state       :1813][INFO    ][12654] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-09 00:50:15,518 [salt.state       :300 ][INFO    ][12654] No changes needed to be made
2019-05-09 00:50:15,518 [salt.state       :1951][INFO    ][12654] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:50:15.518867 duration_in_ms=18.333
2019-05-09 00:50:15,519 [salt.state       :1780][INFO    ][12654] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 00:50:15.519617
2019-05-09 00:50:15,520 [salt.state       :1813][INFO    ][12654] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-09 00:50:15,557 [salt.state       :300 ][INFO    ][12654] No changes needed to be made
2019-05-09 00:50:15,558 [salt.state       :1951][INFO    ][12654] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 00:50:15.557954 duration_in_ms=38.336
2019-05-09 00:50:15,558 [salt.state       :1780][INFO    ][12654] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 00:50:15.558703
2019-05-09 00:50:15,559 [salt.state       :1813][INFO    ][12654] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-09 00:50:15,573 [salt.state       :300 ][INFO    ][12654] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-09 00:50:15,573 [salt.state       :1951][INFO    ][12654] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 00:50:15.573439 duration_in_ms=14.736
2019-05-09 00:50:15,576 [salt.state       :1780][INFO    ][12654] Running state [a2enmod headers] at time 00:50:15.576248
2019-05-09 00:50:15,576 [salt.state       :1813][INFO    ][12654] Executing state cmd.run for [a2enmod headers]
2019-05-09 00:50:15,577 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command 'a2enmod headers' in directory '/root'
2019-05-09 00:50:15,651 [salt.state       :300 ][INFO    ][12654] {'pid': 12673, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-09 00:50:15,651 [salt.state       :1951][INFO    ][12654] Completed state [a2enmod headers] at time 00:50:15.651608 duration_in_ms=75.359
2019-05-09 00:50:15,652 [salt.state       :1780][INFO    ][12654] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 00:50:15.652211
2019-05-09 00:50:15,652 [salt.state       :1813][INFO    ][12654] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-09 00:50:15,672 [salt.state       :300 ][INFO    ][12654] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-09 00:50:15,672 [salt.state       :1951][INFO    ][12654] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 00:50:15.672610 duration_in_ms=20.399
2019-05-09 00:50:15,673 [salt.state       :1780][INFO    ][12654] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 00:50:15.673467
2019-05-09 00:50:15,673 [salt.state       :1813][INFO    ][12654] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-09 00:50:15,750 [salt.state       :300 ][INFO    ][12654] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-09 00:50:15,750 [salt.state       :1951][INFO    ][12654] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 00:50:15.750657 duration_in_ms=77.19
2019-05-09 00:50:15,751 [salt.state       :1780][INFO    ][12654] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 00:50:15.751464
2019-05-09 00:50:15,752 [salt.state       :1813][INFO    ][12654] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-09 00:50:15,834 [salt.state       :300 ][INFO    ][12654] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-09 00:50:15,834 [salt.state       :1951][INFO    ][12654] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 00:50:15.834557 duration_in_ms=83.093
2019-05-09 00:50:15,835 [salt.state       :1780][INFO    ][12654] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 00:50:15.835394
2019-05-09 00:50:15,835 [salt.state       :1813][INFO    ][12654] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-09 00:50:15,911 [salt.state       :300 ][INFO    ][12654] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-09 00:50:15,912 [salt.state       :1951][INFO    ][12654] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 00:50:15.912051 duration_in_ms=76.658
2019-05-09 00:50:15,912 [salt.state       :1780][INFO    ][12654] Running state [/root/.pgpass] at time 00:50:15.912415
2019-05-09 00:50:15,912 [salt.state       :1813][INFO    ][12654] Executing state file.managed for [/root/.pgpass]
2019-05-09 00:50:15,971 [salt.state       :300 ][INFO    ][12654] File /root/.pgpass is in the correct state
2019-05-09 00:50:15,971 [salt.state       :1951][INFO    ][12654] Completed state [/root/.pgpass] at time 00:50:15.971833 duration_in_ms=59.417
2019-05-09 00:50:15,979 [salt.state       :1780][INFO    ][12654] Running state [maas-region syncdb --noinput] at time 00:50:15.978952
2019-05-09 00:50:15,979 [salt.state       :1813][INFO    ][12654] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-09 00:50:15,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-09 00:50:18,208 [salt.state       :300 ][INFO    ][12654] {'pid': 12686, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, maasserver, piston3, auth, metadataserver, contenttypes, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-09 00:50:18,209 [salt.state       :1951][INFO    ][12654] Completed state [maas-region syncdb --noinput] at time 00:50:18.208983 duration_in_ms=2230.03
2019-05-09 00:50:18,209 [salt.state       :2022][WARNING ][12654] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-09 00:50:18,212 [salt.state       :1780][INFO    ][12654] Running state [maas-regiond] at time 00:50:18.212304
2019-05-09 00:50:18,212 [salt.state       :1813][INFO    ][12654] Executing state service.running for [maas-regiond]
2019-05-09 00:50:18,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-09 00:50:18,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-09 00:50:18,270 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-09 00:50:18,287 [salt.state       :300 ][INFO    ][12654] The service maas-regiond is already running
2019-05-09 00:50:18,288 [salt.state       :1951][INFO    ][12654] Completed state [maas-regiond] at time 00:50:18.288338 duration_in_ms=76.034
2019-05-09 00:50:18,291 [salt.state       :1780][INFO    ][12654] Running state [bind9] at time 00:50:18.291041
2019-05-09 00:50:18,291 [salt.state       :1813][INFO    ][12654] Executing state service.running for [bind9]
2019-05-09 00:50:18,292 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-09 00:50:18,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-09 00:50:18,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-09 00:50:18,350 [salt.state       :300 ][INFO    ][12654] The service bind9 is already running
2019-05-09 00:50:18,350 [salt.state       :1951][INFO    ][12654] Completed state [bind9] at time 00:50:18.350829 duration_in_ms=59.787
2019-05-09 00:50:18,353 [salt.state       :1780][INFO    ][12654] Running state [apache2] at time 00:50:18.353665
2019-05-09 00:50:18,354 [salt.state       :1813][INFO    ][12654] Executing state service.running for [apache2]
2019-05-09 00:50:18,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-09 00:50:18,375 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-09 00:50:18,392 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-09 00:50:18,414 [salt.state       :300 ][INFO    ][12654] The service apache2 is already running
2019-05-09 00:50:18,415 [salt.state       :1951][INFO    ][12654] Completed state [apache2] at time 00:50:18.415259 duration_in_ms=61.594
2019-05-09 00:50:18,417 [salt.state       :1780][INFO    ][12654] Running state [maasng.wait_for_http_code] at time 00:50:18.417691
2019-05-09 00:50:18,418 [salt.state       :1813][INFO    ][12654] Executing state module.run for [maasng.wait_for_http_code]
2019-05-09 00:50:18,418 [salt.utils.decorators:613 ][WARNING ][12654] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:50:18,428 [salt.state       :300 ][INFO    ][12654] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-09 00:50:18,429 [salt.state       :1951][INFO    ][12654] Completed state [maasng.wait_for_http_code] at time 00:50:18.429297 duration_in_ms=11.606
2019-05-09 00:50:18,430 [salt.state       :1780][INFO    ][12654] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 00:50:18.430631
2019-05-09 00:50:18,431 [salt.state       :1813][INFO    ][12654] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-09 00:50:18,431 [salt.state       :300 ][INFO    ][12654] /var/lib/maas/.setup_admin exists
2019-05-09 00:50:18,432 [salt.state       :1951][INFO    ][12654] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 00:50:18.432161 duration_in_ms=1.53
2019-05-09 00:50:18,433 [salt.state       :1780][INFO    ][12654] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:50:18.433286
2019-05-09 00:50:18,433 [salt.state       :1813][INFO    ][12654] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 00:50:18,434 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12654] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 00:50:19,865 [salt.state       :300 ][INFO    ][12654] {'pid': 12706, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 00:50:19,866 [salt.state       :1951][INFO    ][12654] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:50:19.866140 duration_in_ms=1432.854
2019-05-09 00:50:19,874 [salt.state       :1780][INFO    ][12654] Running state [maas_region_boot_source_resources_mirror] at time 00:50:19.874524
2019-05-09 00:50:19,875 [salt.state       :1813][INFO    ][12654] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-09 00:50:19,950 [salt.state       :300 ][INFO    ][12654] {'changes': {}}
2019-05-09 00:50:19,951 [salt.state       :1951][INFO    ][12654] Completed state [maas_region_boot_source_resources_mirror] at time 00:50:19.951129 duration_in_ms=76.605
2019-05-09 00:50:19,952 [salt.state       :1780][INFO    ][12654] Running state [maasng.boot_resources_import] at time 00:50:19.952023
2019-05-09 00:50:19,952 [salt.state       :1813][INFO    ][12654] Executing state module.run for [maasng.boot_resources_import]
2019-05-09 00:50:19,952 [salt.utils.decorators:613 ][WARNING ][12654] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:50:23,205 [salt.loaded.ext.module.maasng:1600][INFO    ][12654] Waiting boot-resources import done
sleep for:5s Left:897.0/900s
2019-05-09 00:50:28,259 [salt.loaded.ext.module.maasng:1600][INFO    ][12654] Waiting boot-resources import done
sleep for:5s Left:892.0/900s
2019-05-09 00:50:28,752 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005028738241
2019-05-09 00:50:28,775 [salt.minion      :1432][INFO    ][12734] Starting a new job with PID 12734
2019-05-09 00:50:28,799 [salt.minion      :1711][INFO    ][12734] Returning information for job: 20190509005028738241
2019-05-09 00:50:33,360 [salt.state       :300 ][INFO    ][12654] {'ret': True}
2019-05-09 00:50:33,361 [salt.state       :1951][INFO    ][12654] Completed state [maasng.boot_resources_import] at time 00:50:33.361010 duration_in_ms=13408.985
2019-05-09 00:50:33,362 [salt.state       :1780][INFO    ][12654] Running state [maas_region_boot_sources_selection_xenial] at time 00:50:33.362168
2019-05-09 00:50:33,362 [salt.state       :1813][INFO    ][12654] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-09 00:50:33,575 [salt.state       :300 ][INFO    ][12654] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-09 00:50:33,576 [salt.state       :1951][INFO    ][12654] Completed state [maas_region_boot_sources_selection_xenial] at time 00:50:33.576092 duration_in_ms=213.924
2019-05-09 00:50:33,577 [salt.state       :1780][INFO    ][12654] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 00:50:33.577426
2019-05-09 00:50:33,577 [salt.state       :1813][INFO    ][12654] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-09 00:50:33,578 [salt.utils.decorators:613 ][WARNING ][12654] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:50:33,578 [salt.loaded.ext.module.maasng:1771][INFO    ][12654] boot-sources sync initiated for ALL Rack's
2019-05-09 00:50:34,618 [salt.state       :300 ][INFO    ][12654] {'ret': True}
2019-05-09 00:50:34,618 [salt.state       :1951][INFO    ][12654] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 00:50:34.618601 duration_in_ms=1041.175
2019-05-09 00:50:34,620 [salt.state       :1780][INFO    ][12654] Running state [maas.process_maas_config] at time 00:50:34.620696
2019-05-09 00:50:34,621 [salt.state       :1813][INFO    ][12654] Executing state module.run for [maas.process_maas_config]
2019-05-09 00:50:34,621 [salt.utils.decorators:613 ][WARNING ][12654] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:50:34,622 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=enable_http_proxy value=True
2019-05-09 00:50:34,685 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=upstream_dns value=8.8.8.8
2019-05-09 00:50:34,756 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=commissioning_distro_series value=xenial
2019-05-09 00:50:34,821 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=default_osystem value=ubuntu
2019-05-09 00:50:37,947 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=active_discovery_interval value=600
2019-05-09 00:50:38,028 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=dnssec_validation value=no
2019-05-09 00:50:38,099 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=maas_name value=mas01
2019-05-09 00:50:38,155 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=network_discovery value=enabled
2019-05-09 00:50:38,300 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=enable_third_party_drivers value=True
2019-05-09 00:50:38,365 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=default_storage_layout value=lvm
2019-05-09 00:50:38,441 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=ntp_external_only value=True
2019-05-09 00:50:38,504 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-09 00:50:38,564 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=default_distro_series value=xenial
2019-05-09 00:50:38,650 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-09 00:50:38,786 [salt.state       :300 ][INFO    ][12654] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-09 00:50:38,786 [salt.state       :1951][INFO    ][12654] Completed state [maas.process_maas_config] at time 00:50:38.786746 duration_in_ms=4166.048
2019-05-09 00:50:38,787 [salt.state       :1780][INFO    ][12654] Running state [pxe_admin] at time 00:50:38.787726
2019-05-09 00:50:38,788 [salt.state       :1813][INFO    ][12654] Executing state maasng.fabric_present for [pxe_admin]
2019-05-09 00:50:38,845 [salt.loaded.ext.module.maasng:945 ][INFO    ][12654] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-09 00:50:38,922 [salt.loaded.ext.module.maasng:1008][WARNING ][12654] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-09 00:50:38,923 [salt.loaded.ext.module.maasng:1011][WARNING ][12654] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-09 00:50:38,998 [salt.state       :300 ][INFO    ][12654] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-09 00:50:38,999 [salt.state       :1951][INFO    ][12654] Completed state [pxe_admin] at time 00:50:38.999026 duration_in_ms=211.3
2019-05-09 00:50:38,999 [salt.state       :1780][INFO    ][12654] Running state [vlan 0] at time 00:50:38.999327
2019-05-09 00:50:38,999 [salt.state       :1813][INFO    ][12654] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-09 00:50:39,072 [salt.loaded.ext.module.maasng:945 ][INFO    ][12654] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xamx3x', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-09 00:50:39,190 [salt.loaded.ext.module.maasng:945 ][INFO    ][12654] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-09 00:50:39,489 [salt.loaded.ext.module.maasng:945 ][INFO    ][12654] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-09 00:50:39,567 [salt.state       :300 ][INFO    ][12654] {'new': 'Vlan untagged was updated'}
2019-05-09 00:50:39,567 [salt.state       :1951][INFO    ][12654] Completed state [vlan 0] at time 00:50:39.567862 duration_in_ms=568.534
2019-05-09 00:50:39,569 [salt.state       :1780][INFO    ][12654] Running state [192.168.11.0/24] at time 00:50:39.568997
2019-05-09 00:50:39,569 [salt.state       :1813][INFO    ][12654] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-09 00:50:39,761 [salt.loaded.ext.module.maasng:945 ][INFO    ][12654] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-09 00:50:39,762 [salt.loaded.ext.module.maasng:1235][WARNING ][12654] Ignoring parameter vlan:0
2019-05-09 00:50:39,839 [salt.state       :300 ][INFO    ][12654] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-09 00:50:39,839 [salt.state       :1951][INFO    ][12654] Completed state [192.168.11.0/24] at time 00:50:39.839886 duration_in_ms=270.888
2019-05-09 00:50:39,841 [salt.state       :1780][INFO    ][12654] Running state [maas_create_iprange_1] at time 00:50:39.841241
2019-05-09 00:50:39,841 [salt.state       :1813][INFO    ][12654] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-09 00:50:39,912 [salt.state       :300 ][INFO    ][12654] Iprange maas_create_iprange_1 already exist.
2019-05-09 00:50:39,912 [salt.state       :1951][INFO    ][12654] Completed state [maas_create_iprange_1] at time 00:50:39.912611 duration_in_ms=71.37
2019-05-09 00:50:39,913 [salt.state       :1780][INFO    ][12654] Running state [vlan 0] at time 00:50:39.913046
2019-05-09 00:50:39,913 [salt.state       :1813][INFO    ][12654] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-09 00:50:39,964 [salt.loaded.ext.module.maasng:945 ][INFO    ][12654] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-09 00:50:40,100 [salt.loaded.ext.module.maasng:945 ][INFO    ][12654] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-09 00:50:40,352 [salt.loaded.ext.module.maasng:945 ][INFO    ][12654] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'xamx3x', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-09 00:50:40,436 [salt.state       :300 ][INFO    ][12654] {'new': 'Vlan untagged was updated'}
2019-05-09 00:50:40,437 [salt.state       :1951][INFO    ][12654] Completed state [vlan 0] at time 00:50:40.437230 duration_in_ms=524.184
2019-05-09 00:50:40,438 [salt.state       :1780][INFO    ][12654] Running state [opnfv] at time 00:50:40.438156
2019-05-09 00:50:40,438 [salt.state       :1813][INFO    ][12654] Executing state maasng.sshkey_present for [opnfv]
2019-05-09 00:50:40,489 [salt.loaded.ext.module.maasng:1903][INFO    ][12654] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-09 00:50:40,490 [salt.state       :300 ][INFO    ][12654] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-09 00:50:40,490 [salt.state       :1951][INFO    ][12654] Completed state [opnfv] at time 00:50:40.490692 duration_in_ms=52.536
2019-05-09 00:50:40,491 [salt.state       :1780][INFO    ][12654] Running state [maas.process_tags] at time 00:50:40.491643
2019-05-09 00:50:40,492 [salt.state       :1813][INFO    ][12654] Executing state module.run for [maas.process_tags]
2019-05-09 00:50:40,492 [salt.utils.decorators:613 ][WARNING ][12654] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:50:40,546 [salt.loaded.ext.module.maas:92  ][INFO    ][12654] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-09 00:50:40,638 [salt.state       :300 ][INFO    ][12654] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-09 00:50:40,638 [salt.state       :1951][INFO    ][12654] Completed state [maas.process_tags] at time 00:50:40.638607 duration_in_ms=146.964
2019-05-09 00:50:40,642 [salt.minion      :1711][INFO    ][12654] Returning information for job: 20190509005013705284
2019-05-09 00:50:41,218 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509005041205253
2019-05-09 00:50:41,234 [salt.minion      :1432][INFO    ][13136] Starting a new job with PID 13136
2019-05-09 00:50:44,531 [salt.state       :915 ][INFO    ][13136] Loading fresh modules for state activity
2019-05-09 00:50:44,624 [salt.state       :1780][INFO    ][13136] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:50:44.623996
2019-05-09 00:50:44,624 [salt.state       :1813][INFO    ][13136] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 00:50:44,626 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13136] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 00:50:46,075 [salt.state       :300 ][INFO    ][13136] {'pid': 13159, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 00:50:46,076 [salt.state       :1951][INFO    ][13136] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:50:46.076166 duration_in_ms=1452.171
2019-05-09 00:50:46,077 [salt.state       :1780][INFO    ][13136] Running state [maas.process_machines] at time 00:50:46.077364
2019-05-09 00:50:46,077 [salt.state       :1813][INFO    ][13136] Executing state module.run for [maas.process_machines]
2019-05-09 00:50:46,078 [salt.utils.decorators:613 ][WARNING ][13136] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:50:46,814 [salt.loaded.ext.module.maas:412 ][WARNING ][13136] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 00:50:46,815 [salt.loaded.ext.module.maas:92  ][INFO    ][13136] machine hostname=gtw01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=y3ypmb architecture=amd64/generic power_parameters_power_user=admin
2019-05-09 00:50:48,096 [salt.loaded.ext.module.maas:412 ][WARNING ][13136] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 00:50:48,097 [salt.loaded.ext.module.maas:92  ][INFO    ][13136] machine hostname=cmp002 power_type=ipmi mac_addresses=['00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=gqba4h architecture=amd64/generic power_parameters_power_user=admin
2019-05-09 00:50:49,377 [salt.loaded.ext.module.maas:412 ][WARNING ][13136] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 00:50:49,378 [salt.loaded.ext.module.maas:92  ][INFO    ][13136] machine hostname=odl01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=admr6s architecture=amd64/generic power_parameters_power_user=admin
2019-05-09 00:50:50,647 [salt.loaded.ext.module.maas:412 ][WARNING ][13136] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 00:50:50,647 [salt.loaded.ext.module.maas:92  ][INFO    ][13136] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=6nynfb architecture=amd64/generic power_parameters_power_user=admin
2019-05-09 00:50:51,946 [salt.loaded.ext.module.maas:412 ][WARNING ][13136] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-09 00:50:51,947 [salt.loaded.ext.module.maas:92  ][INFO    ][13136] machine hostname=ctl01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=hqp8pk architecture=amd64/generic power_parameters_power_user=admin
2019-05-09 00:50:53,018 [salt.state       :300 ][INFO    ][13136] {'ret': {'updated': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-05-09 00:50:53,019 [salt.state       :1951][INFO    ][13136] Completed state [maas.process_machines] at time 00:50:53.019082 duration_in_ms=6941.716
2019-05-09 00:50:53,022 [salt.minion      :1711][INFO    ][13136] Returning information for job: 20190509005041205253
2019-05-09 00:51:25,452 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509005125440060
2019-05-09 00:51:25,469 [salt.minion      :1432][INFO    ][13385] Starting a new job with PID 13385
2019-05-09 00:51:29,229 [salt.state       :915 ][INFO    ][13385] Loading fresh modules for state activity
2019-05-09 00:51:29,319 [salt.state       :1780][INFO    ][13385] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:51:29.319271
2019-05-09 00:51:29,319 [salt.state       :1813][INFO    ][13385] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 00:51:29,321 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13385] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 00:51:30,904 [salt.state       :300 ][INFO    ][13385] {'pid': 13394, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 00:51:30,905 [salt.state       :1951][INFO    ][13385] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:51:30.905373 duration_in_ms=1586.101
2019-05-09 00:51:30,908 [salt.state       :1780][INFO    ][13385] Running state [maas.wait_for_machine_status] at time 00:51:30.908779
2019-05-09 00:51:30,909 [salt.state       :1813][INFO    ][13385] Executing state module.run for [maas.wait_for_machine_status]
2019-05-09 00:51:30,910 [salt.utils.decorators:613 ][WARNING ][13385] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:51:31,423 [salt.loaded.ext.module.maas:993 ][INFO    ][13385] Machine y3ypmb mark broken
2019-05-09 00:51:32,035 [salt.loaded.ext.module.maas:996 ][INFO    ][13385] Machine y3ypmb mark fixed
2019-05-09 00:51:33,293 [salt.loaded.ext.module.maas:684 ][INFO    ][13385] deploymachines hwe_kernel=hwe-16.04 system_id=y3ypmb distro_series=xenial
2019-05-09 00:51:38,885 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1492.03541088s left)
2019-05-09 00:51:40,562 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005140501969
2019-05-09 00:51:40,582 [salt.minion      :1432][INFO    ][13505] Starting a new job with PID 13505
2019-05-09 00:51:40,605 [salt.minion      :1711][INFO    ][13505] Returning information for job: 20190509005140501969
2019-05-09 00:52:10,601 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005210588181
2019-05-09 00:52:10,621 [salt.minion      :1432][INFO    ][13530] Starting a new job with PID 13530
2019-05-09 00:52:10,648 [salt.minion      :1711][INFO    ][13530] Returning information for job: 20190509005210588181
2019-05-09 00:52:12,475 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1458.44481778s left)
2019-05-09 00:52:40,718 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005240706431
2019-05-09 00:52:40,741 [salt.minion      :1432][INFO    ][13580] Starting a new job with PID 13580
2019-05-09 00:52:40,767 [salt.minion      :1711][INFO    ][13580] Returning information for job: 20190509005240706431
2019-05-09 00:52:46,050 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1424.87065983s left)
2019-05-09 00:53:10,767 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005310756522
2019-05-09 00:53:10,788 [salt.minion      :1432][INFO    ][13607] Starting a new job with PID 13607
2019-05-09 00:53:10,810 [salt.minion      :1711][INFO    ][13607] Returning information for job: 20190509005310756522
2019-05-09 00:53:19,851 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1391.06959677s left)
2019-05-09 00:53:40,817 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005340804127
2019-05-09 00:53:40,840 [salt.minion      :1432][INFO    ][13755] Starting a new job with PID 13755
2019-05-09 00:53:40,865 [salt.minion      :1711][INFO    ][13755] Returning information for job: 20190509005340804127
2019-05-09 00:53:53,141 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1357.77967882s left)
2019-05-09 00:54:10,875 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005410861892
2019-05-09 00:54:10,898 [salt.minion      :1432][INFO    ][13795] Starting a new job with PID 13795
2019-05-09 00:54:10,925 [salt.minion      :1711][INFO    ][13795] Returning information for job: 20190509005410861892
2019-05-09 00:54:26,464 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1324.45664477s left)
2019-05-09 00:54:40,937 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005440925575
2019-05-09 00:54:40,960 [salt.minion      :1432][INFO    ][13963] Starting a new job with PID 13963
2019-05-09 00:54:40,988 [salt.minion      :1711][INFO    ][13963] Returning information for job: 20190509005440925575
2019-05-09 00:55:00,021 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1290.89904785s left)
2019-05-09 00:55:11,005 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005510992516
2019-05-09 00:55:11,028 [salt.minion      :1432][INFO    ][13986] Starting a new job with PID 13986
2019-05-09 00:55:11,053 [salt.minion      :1711][INFO    ][13986] Returning information for job: 20190509005510992516
2019-05-09 00:55:33,489 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1257.43139482s left)
2019-05-09 00:55:41,075 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005541067090
2019-05-09 00:55:41,095 [salt.minion      :1432][INFO    ][14079] Starting a new job with PID 14079
2019-05-09 00:55:41,120 [salt.minion      :1711][INFO    ][14079] Returning information for job: 20190509005541067090
2019-05-09 00:56:06,940 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1223.97985077s left)
2019-05-09 00:56:11,137 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005611125084
2019-05-09 00:56:11,161 [salt.minion      :1432][INFO    ][14101] Starting a new job with PID 14101
2019-05-09 00:56:11,187 [salt.minion      :1711][INFO    ][14101] Returning information for job: 20190509005611125084
2019-05-09 00:56:40,244 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1190.67581391s left)
2019-05-09 00:56:41,212 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005641200098
2019-05-09 00:56:41,234 [salt.minion      :1432][INFO    ][14257] Starting a new job with PID 14257
2019-05-09 00:56:41,260 [salt.minion      :1711][INFO    ][14257] Returning information for job: 20190509005641200098
2019-05-09 00:57:11,275 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005711262997
2019-05-09 00:57:11,297 [salt.minion      :1432][INFO    ][14281] Starting a new job with PID 14281
2019-05-09 00:57:11,323 [salt.minion      :1711][INFO    ][14281] Returning information for job: 20190509005711262997
2019-05-09 00:57:13,833 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1157.08765197s left)
2019-05-09 00:57:41,355 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005741342890
2019-05-09 00:57:41,377 [salt.minion      :1432][INFO    ][14401] Starting a new job with PID 14401
2019-05-09 00:57:41,403 [salt.minion      :1711][INFO    ][14401] Returning information for job: 20190509005741342890
2019-05-09 00:57:47,527 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1123.39299393s left)
2019-05-09 00:58:11,444 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005811431378
2019-05-09 00:58:11,467 [salt.minion      :1432][INFO    ][14424] Starting a new job with PID 14424
2019-05-09 00:58:11,493 [salt.minion      :1711][INFO    ][14424] Returning information for job: 20190509005811431378
2019-05-09 00:58:21,263 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1089.65704298s left)
2019-05-09 00:58:41,539 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005841526598
2019-05-09 00:58:41,561 [salt.minion      :1432][INFO    ][14514] Starting a new job with PID 14514
2019-05-09 00:58:41,589 [salt.minion      :1711][INFO    ][14514] Returning information for job: 20190509005841526598
2019-05-09 00:58:54,825 [salt.loaded.ext.module.maas:1023][INFO    ][13385] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1056.09531093s left)
2019-05-09 00:59:11,619 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command saltutil.find_job with jid 20190509005911605350
2019-05-09 00:59:11,645 [salt.minion      :1432][INFO    ][14571] Starting a new job with PID 14571
2019-05-09 00:59:11,674 [salt.minion      :1711][INFO    ][14571] Returning information for job: 20190509005911605350
2019-05-09 00:59:28,527 [salt.state       :300 ][INFO    ][13385] {'ret': True}
2019-05-09 00:59:28,528 [salt.state       :1951][INFO    ][13385] Completed state [maas.wait_for_machine_status] at time 00:59:28.527989 duration_in_ms=477619.208
2019-05-09 00:59:28,531 [salt.minion      :1711][INFO    ][13385] Returning information for job: 20190509005125440060
2019-05-09 00:59:29,233 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509005929219952
2019-05-09 00:59:29,254 [salt.minion      :1432][INFO    ][14596] Starting a new job with PID 14596
2019-05-09 00:59:32,951 [salt.state       :915 ][INFO    ][14596] Loading fresh modules for state activity
2019-05-09 00:59:33,030 [salt.state       :1780][INFO    ][14596] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:59:33.030688
2019-05-09 00:59:33,031 [salt.state       :1813][INFO    ][14596] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 00:59:33,032 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14596] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 00:59:34,443 [salt.state       :300 ][INFO    ][14596] {'pid': 14620, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 00:59:34,444 [salt.state       :1951][INFO    ][14596] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:59:34.443994 duration_in_ms=1413.306
2019-05-09 00:59:34,445 [salt.state       :1780][INFO    ][14596] Running state [maas_machines_storage_cmp002_lvm] at time 00:59:34.445335
2019-05-09 00:59:34,445 [salt.state       :1813][INFO    ][14596] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-09 00:59:35,115 [salt.state       :300 ][INFO    ][14596] Machine cmp002 is not in Ready state.
2019-05-09 00:59:35,116 [salt.state       :1951][INFO    ][14596] Completed state [maas_machines_storage_cmp002_lvm] at time 00:59:35.116352 duration_in_ms=671.015
2019-05-09 00:59:35,117 [salt.state       :1780][INFO    ][14596] Running state [maas_machines_storage_cmp001_lvm] at time 00:59:35.116940
2019-05-09 00:59:35,117 [salt.state       :1813][INFO    ][14596] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-09 00:59:35,849 [salt.state       :300 ][INFO    ][14596] Machine cmp001 is not in Ready state.
2019-05-09 00:59:35,850 [salt.state       :1951][INFO    ][14596] Completed state [maas_machines_storage_cmp001_lvm] at time 00:59:35.849943 duration_in_ms=733.003
2019-05-09 00:59:35,854 [salt.minion      :1711][INFO    ][14596] Returning information for job: 20190509005929219952
2019-05-09 00:59:36,427 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509005936414855
2019-05-09 00:59:36,449 [salt.minion      :1432][INFO    ][14630] Starting a new job with PID 14630
2019-05-09 00:59:37,206 [salt.state       :915 ][INFO    ][14630] Loading fresh modules for state activity
2019-05-09 00:59:37,258 [salt.state       :1780][INFO    ][14630] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:59:37.257941
2019-05-09 00:59:37,258 [salt.state       :1813][INFO    ][14630] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 00:59:37,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14630] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 00:59:38,514 [salt.state       :300 ][INFO    ][14630] {'pid': 14672, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 00:59:38,514 [salt.state       :1951][INFO    ][14630] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:59:38.514844 duration_in_ms=1256.901
2019-05-09 00:59:38,517 [salt.state       :1780][INFO    ][14630] Running state [maas.deploy_machines] at time 00:59:38.517569
2019-05-09 00:59:38,518 [salt.state       :1813][INFO    ][14630] Executing state module.run for [maas.deploy_machines]
2019-05-09 00:59:38,518 [salt.utils.decorators:613 ][WARNING ][14630] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:59:39,272 [salt.state       :300 ][INFO    ][14630] {'ret': {'updated': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-05-09 00:59:39,273 [salt.state       :1951][INFO    ][14630] Completed state [maas.deploy_machines] at time 00:59:39.273136 duration_in_ms=755.566
2019-05-09 00:59:39,276 [salt.minion      :1711][INFO    ][14630] Returning information for job: 20190509005936414855
2019-05-09 00:59:39,904 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command state.apply with jid 20190509005939889006
2019-05-09 00:59:39,925 [salt.minion      :1432][INFO    ][14794] Starting a new job with PID 14794
2019-05-09 00:59:40,589 [salt.state       :915 ][INFO    ][14794] Loading fresh modules for state activity
2019-05-09 00:59:40,638 [salt.state       :1780][INFO    ][14794] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:59:40.638256
2019-05-09 00:59:40,638 [salt.state       :1813][INFO    ][14794] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-09 00:59:40,639 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14794] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-09 00:59:42,051 [salt.state       :300 ][INFO    ][14794] {'pid': 14802, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-09 00:59:42,052 [salt.state       :1951][INFO    ][14794] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:59:42.052363 duration_in_ms=1414.108
2019-05-09 00:59:42,053 [salt.state       :1780][INFO    ][14794] Running state [maas.wait_for_machine_status] at time 00:59:42.053460
2019-05-09 00:59:42,053 [salt.state       :1813][INFO    ][14794] Executing state module.run for [maas.wait_for_machine_status]
2019-05-09 00:59:42,053 [salt.utils.decorators:613 ][WARNING ][14794] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-09 00:59:45,126 [salt.state       :300 ][INFO    ][14794] {'ret': True}
2019-05-09 00:59:45,126 [salt.state       :1951][INFO    ][14794] Completed state [maas.wait_for_machine_status] at time 00:59:45.126748 duration_in_ms=3073.285
2019-05-09 00:59:45,130 [salt.minion      :1711][INFO    ][14794] Returning information for job: 20190509005939889006
2019-05-09 01:29:49,649 [salt.utils.schedule:1377][INFO    ][6639] Running scheduled job: __mine_interval
2019-05-09 01:54:42,690 [salt.minion      :1308][INFO    ][6639] User sudo_ubuntu Executing command cp.push_dir with jid 20190509015442678362
2019-05-09 01:54:42,711 [salt.minion      :1432][INFO    ][18978] Starting a new job with PID 18978
