2019-06-27 02:06:42,863 [salt.utils.decorators:613 ][WARNING ][2408] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:06:43,944 [salt.utils.decorators:613 ][WARNING ][2408] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:06:47,591 [salt.loaded.int.states.file:2298][WARNING ][2651] 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-06-27 02:07:10,474 [salt.state       :2022][WARNING ][2891] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-27 02:07:13,654 [salt.utils.decorators:613 ][WARNING ][2891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:07:26,956 [salt.utils.decorators:613 ][WARNING ][2891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:07:55,081 [salt.utils.decorators:613 ][WARNING ][2891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:07:56,384 [salt.utils.decorators:613 ][WARNING ][2891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:07:58,628 [salt.loaded.ext.module.maasng:1008][WARNING ][2891] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-27 02:07:58,629 [salt.loaded.ext.module.maasng:1011][WARNING ][2891] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-27 02:07:59,470 [salt.loaded.ext.module.maasng:1235][WARNING ][2891] Ignoring parameter vlan:0
2019-06-27 02:08:13,565 [salt.utils.decorators:613 ][WARNING ][5449] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:08:13,650 [salt.loaded.ext.module.maas:412 ][WARNING ][5449] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-27 02:08:15,295 [salt.loaded.ext.module.maas:412 ][WARNING ][5449] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-27 02:08:16,788 [salt.loaded.ext.module.maas:412 ][WARNING ][5449] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-27 02:08:18,313 [salt.loaded.ext.module.maas:412 ][WARNING ][5449] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-27 02:08:19,701 [salt.loaded.ext.module.maas:412 ][WARNING ][5449] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-27 02:08:26,328 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6057] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-27 02:08:26,375 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6057] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-27 02:08:26,443 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-06-27 02:08:27,792 [salt.cli.daemons :293 ][INFO    ][6115] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-06-27 02:08:27,976 [salt.cli.daemons :82  ][INFO    ][6115] Starting up the Salt Minion
2019-06-27 02:08:27,977 [salt.utils.event :1017][INFO    ][6115] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-06-27 02:08:29,497 [salt.minion      :976 ][INFO    ][6115] Creating minion process manager
2019-06-27 02:08:32,017 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['date', '+%z'] in directory '/root'
2019-06-27 02:08:32,049 [salt.utils.schedule:568 ][INFO    ][6115] Updating job settings for scheduled job: __mine_interval
2019-06-27 02:08:32,051 [salt.minion      :1108][INFO    ][6115] Added mine.update to scheduler
2019-06-27 02:08:32,057 [salt.minion      :1975][INFO    ][6115] Minion is starting as user 'root'
2019-06-27 02:08:32,084 [salt.minion      :2336][INFO    ][6115] Minion is ready to receive requests!
2019-06-27 02:08:52,767 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627020852756955
2019-06-27 02:08:52,792 [salt.minion      :1432][INFO    ][6197] Starting a new job with PID 6197
2019-06-27 02:09:00,942 [salt.state       :915 ][INFO    ][6197] Loading fresh modules for state activity
2019-06-27 02:09:01,000 [salt.fileclient  :1219][INFO    ][6197] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-27 02:09:01,057 [salt.state       :1780][INFO    ][6197] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:01.057317
2019-06-27 02:09:01,057 [salt.state       :1813][INFO    ][6197] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-27 02:09:01,060 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6197] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-27 02:09:02,936 [salt.state       :300 ][INFO    ][6197] {'pid': 6218, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-27 02:09:02,938 [salt.state       :1951][INFO    ][6197] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:02.937524 duration_in_ms=1880.206
2019-06-27 02:09:02,941 [salt.state       :1780][INFO    ][6197] Running state [maas.wait_for_machine_status] at time 02:09:02.941092
2019-06-27 02:09:02,943 [salt.state       :1813][INFO    ][6197] Executing state module.run for [maas.wait_for_machine_status]
2019-06-27 02:09:02,943 [salt.utils.decorators:613 ][WARNING ][6197] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:09:03,756 [salt.loaded.ext.module.maas:1023][INFO    ][6197] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.19946694s left)
2019-06-27 02:09:07,804 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627020907790134
2019-06-27 02:09:07,829 [salt.minion      :1432][INFO    ][6229] Starting a new job with PID 6229
2019-06-27 02:09:07,856 [salt.minion      :1711][INFO    ][6229] Returning information for job: 20190627020907790134
2019-06-27 02:09:34,619 [salt.loaded.ext.module.maas:1023][INFO    ][6197] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.33610296s left)
2019-06-27 02:09:37,894 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627020937881165
2019-06-27 02:09:37,918 [salt.minion      :1432][INFO    ][6273] Starting a new job with PID 6273
2019-06-27 02:09:37,947 [salt.minion      :1711][INFO    ][6273] Returning information for job: 20190627020937881165
2019-06-27 02:10:05,547 [salt.loaded.ext.module.maas:1023][INFO    ][6197] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.40841985s left)
2019-06-27 02:10:08,000 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021007986721
2019-06-27 02:10:08,025 [salt.minion      :1432][INFO    ][6301] Starting a new job with PID 6301
2019-06-27 02:10:08,060 [salt.minion      :1711][INFO    ][6301] Returning information for job: 20190627021007986721
2019-06-27 02:10:36,431 [salt.loaded.ext.module.maas:1023][INFO    ][6197] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.52448988s left)
2019-06-27 02:10:38,095 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021038080011
2019-06-27 02:10:38,123 [salt.minion      :1432][INFO    ][6349] Starting a new job with PID 6349
2019-06-27 02:10:38,149 [salt.minion      :1711][INFO    ][6349] Returning information for job: 20190627021038080011
2019-06-27 02:11:07,564 [salt.loaded.ext.module.maas:1023][INFO    ][6197] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.39166403s left)
2019-06-27 02:11:08,173 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021108162360
2019-06-27 02:11:08,200 [salt.minion      :1432][INFO    ][6406] Starting a new job with PID 6406
2019-06-27 02:11:08,231 [salt.minion      :1711][INFO    ][6406] Returning information for job: 20190627021108162360
2019-06-27 02:11:38,303 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021138289607
2019-06-27 02:11:38,325 [salt.minion      :1432][INFO    ][6590] Starting a new job with PID 6590
2019-06-27 02:11:38,358 [salt.minion      :1711][INFO    ][6590] Returning information for job: 20190627021138289607
2019-06-27 02:11:38,810 [salt.loaded.ext.module.maas:1023][INFO    ][6197] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.14488792s left)
2019-06-27 02:12:08,411 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021208394406
2019-06-27 02:12:08,443 [salt.minion      :1432][INFO    ][6655] Starting a new job with PID 6655
2019-06-27 02:12:08,472 [salt.minion      :1711][INFO    ][6655] Returning information for job: 20190627021208394406
2019-06-27 02:12:10,195 [salt.loaded.ext.module.maas:1023][INFO    ][6197] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.76050305s left)
2019-06-27 02:12:38,562 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021238547565
2019-06-27 02:12:38,578 [salt.minion      :1432][INFO    ][6996] Starting a new job with PID 6996
2019-06-27 02:12:38,604 [salt.minion      :1711][INFO    ][6996] Returning information for job: 20190627021238547565
2019-06-27 02:12:41,671 [salt.loaded.ext.module.maas:1023][INFO    ][6197] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.28443289s left)
2019-06-27 02:13:08,691 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021308672304
2019-06-27 02:13:08,717 [salt.minion      :1432][INFO    ][7121] Starting a new job with PID 7121
2019-06-27 02:13:08,746 [salt.minion      :1711][INFO    ][7121] Returning information for job: 20190627021308672304
2019-06-27 02:13:13,434 [salt.loaded.ext.module.maas:1023][INFO    ][6197] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.52119303s left)
2019-06-27 02:13:38,888 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021338874359
2019-06-27 02:13:38,909 [salt.minion      :1432][INFO    ][7491] Starting a new job with PID 7491
2019-06-27 02:13:38,939 [salt.minion      :1711][INFO    ][7491] Returning information for job: 20190627021338874359
2019-06-27 02:13:45,715 [salt.loaded.ext.module.maas:1023][INFO    ][6197] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.24001193s left)
2019-06-27 02:14:09,029 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021409013414
2019-06-27 02:14:09,058 [salt.minion      :1432][INFO    ][7560] Starting a new job with PID 7560
2019-06-27 02:14:09,087 [salt.minion      :1711][INFO    ][7560] Returning information for job: 20190627021409013414
2019-06-27 02:14:17,880 [salt.loaded.ext.module.maas:1023][INFO    ][6197] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.07549596s left)
2019-06-27 02:14:39,196 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021439188351
2019-06-27 02:14:39,210 [salt.minion      :1432][INFO    ][7822] Starting a new job with PID 7822
2019-06-27 02:14:39,240 [salt.minion      :1711][INFO    ][7822] Returning information for job: 20190627021439188351
2019-06-27 02:14:51,031 [salt.state       :300 ][INFO    ][6197] {'ret': True}
2019-06-27 02:14:51,032 [salt.state       :1951][INFO    ][6197] Completed state [maas.wait_for_machine_status] at time 02:14:51.032305 duration_in_ms=348091.214
2019-06-27 02:14:51,037 [salt.minion      :1711][INFO    ][6197] Returning information for job: 20190627020852756955
2019-06-27 02:14:51,780 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627021451761670
2019-06-27 02:14:51,805 [salt.minion      :1432][INFO    ][7867] Starting a new job with PID 7867
2019-06-27 02:15:00,176 [salt.state       :915 ][INFO    ][7867] Loading fresh modules for state activity
2019-06-27 02:15:00,240 [salt.fileclient  :1219][INFO    ][7867] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-27 02:15:00,353 [salt.state       :1780][INFO    ][7867] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:00.353118
2019-06-27 02:15:00,353 [salt.state       :1813][INFO    ][7867] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-27 02:15:00,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7867] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-27 02:15:02,320 [salt.state       :300 ][INFO    ][7867] {'pid': 7891, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-27 02:15:02,321 [salt.state       :1951][INFO    ][7867] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:02.321102 duration_in_ms=1967.984
2019-06-27 02:15:02,325 [salt.state       :1780][INFO    ][7867] Running state [maas_machines_storage_cmp002_lvm] at time 02:15:02.325350
2019-06-27 02:15:02,325 [salt.state       :1813][INFO    ][7867] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-27 02:15:03,627 [salt.loaded.ext.module.maasng:610 ][INFO    ][7867] gecfx7
2019-06-27 02:15:03,628 [salt.loaded.ext.module.maasng:626 ][INFO    ][7867] sda
2019-06-27 02:15:04,200 [salt.loaded.ext.module.maasng:361 ][INFO    ][7867] gecfx7
2019-06-27 02:15:04,299 [salt.loaded.ext.module.maasng:367 ][INFO    ][7867] [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/blockdevices/1/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'a08e4cd3-1545-4574-805e-8b7d833720c2', u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gecfx7', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bfae8ee1-156d-4e0c-a9db-a6f269a71296', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'gecfx7', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/blockdevices/3/', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'gecfx7', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'886d1e6f-c768-4369-9f65-be7e5cd1c54f', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'serial': None, u'uuid': u'b1c2dfde-596f-4d1e-a107-2d00865bd666'}]
2019-06-27 02:15:04,299 [salt.loaded.ext.module.maasng:632 ][INFO    ][7867] vgroot
2019-06-27 02:15:04,300 [salt.loaded.ext.module.maasng:635 ][INFO    ][7867] lvroot
2019-06-27 02:15:04,300 [salt.loaded.ext.module.maasng:639 ][INFO    ][7867] 107374182400
2019-06-27 02:15:04,900 [salt.loaded.ext.module.maasng:645 ][INFO    ][7867] {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'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', 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'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'filesystem': None, u'id': 1, u'system_id': u'gecfx7', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'ea6c1b7a-e4c3-45e4-bcf3-a5c016c4b09b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gecfx7', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7fcc74d8-12d3-444a-b5b1-3e8f0076be71', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/blockdevices/1/partition/6'}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/blockdevices/11/', u'uuid': u'aaa605ca-d17f-46f3-af82-369e86087192', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'166581bb-11e9-4004-afd3-afb2afe217f3', u'fstype': u'ext4'}, u'id': 11, u'system_id': u'gecfx7', 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'used_size': 107374182400, u'serial': None, u'partitions': []}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/gecfx7/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'gecfx7', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'gecfx7', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'filesystem': None, u'id': 1, u'system_id': u'gecfx7', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'ea6c1b7a-e4c3-45e4-bcf3-a5c016c4b09b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gecfx7', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7fcc74d8-12d3-444a-b5b1-3e8f0076be71', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/blockdevices/1/partition/6'}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'filesystem': None, u'id': 1, u'system_id': u'gecfx7', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'ea6c1b7a-e4c3-45e4-bcf3-a5c016c4b09b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gecfx7', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7fcc74d8-12d3-444a-b5b1-3e8f0076be71', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/blockdevices/1/partition/6'}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/blockdevices/11/', u'uuid': u'aaa605ca-d17f-46f3-af82-369e86087192', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'166581bb-11e9-4004-afd3-afb2afe217f3', u'fstype': u'ext4'}, u'id': 11, u'system_id': u'gecfx7', 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'used_size': 107374182400, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'gecfx7', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/interfaces/4/'}, u'interface_set': [{u'name': u'eno1', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'gecfx7', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'gecfx7', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/interfaces/11/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'gecfx7', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/interfaces/12/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, 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'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'gecfx7', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, 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'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'gecfx7', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/interfaces/14/'}, {u'name': u'eno2', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'gecfx7', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/gecfx7/interfaces/15/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-27 02:15:04,903 [salt.state       :300 ][INFO    ][7867] {'new': {'storage_layout': 'lvm'}}
2019-06-27 02:15:04,904 [salt.state       :1951][INFO    ][7867] Completed state [maas_machines_storage_cmp002_lvm] at time 02:15:04.904043 duration_in_ms=2578.691
2019-06-27 02:15:04,904 [salt.state       :1780][INFO    ][7867] Running state [maas_machines_storage_cmp001_lvm] at time 02:15:04.904657
2019-06-27 02:15:04,905 [salt.state       :1813][INFO    ][7867] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-27 02:15:06,033 [salt.loaded.ext.module.maasng:610 ][INFO    ][7867] qndbm6
2019-06-27 02:15:06,033 [salt.loaded.ext.module.maasng:626 ][INFO    ][7867] sda
2019-06-27 02:15:06,618 [salt.loaded.ext.module.maasng:361 ][INFO    ][7867] qndbm6
2019-06-27 02:15:06,706 [salt.loaded.ext.module.maasng:367 ][INFO    ][7867] [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/blockdevices/2/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'cb463ff5-e07b-4579-aaaf-82f8dc627ad9', u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qndbm6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'102d7fa0-7ed3-4d4d-abb4-9d9581e49096', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qndbm6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/blockdevices/4/', u'used_size': 800097042432, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'qndbm6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'92c1acd3-58ce-4d8e-b4c9-678b4ce30c88', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 4, u'serial': None, u'uuid': u'afccea28-3c25-44ea-8875-7963ed30a825'}]
2019-06-27 02:15:06,707 [salt.loaded.ext.module.maasng:632 ][INFO    ][7867] vgroot
2019-06-27 02:15:06,707 [salt.loaded.ext.module.maasng:635 ][INFO    ][7867] lvroot
2019-06-27 02:15:06,708 [salt.loaded.ext.module.maasng:639 ][INFO    ][7867] 107374182400
2019-06-27 02:15:06,821 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021506803144
2019-06-27 02:15:06,847 [salt.minion      :1432][INFO    ][7931] Starting a new job with PID 7931
2019-06-27 02:15:06,876 [salt.minion      :1711][INFO    ][7931] Returning information for job: 20190627021506803144
2019-06-27 02:15:07,328 [salt.loaded.ext.module.maasng:645 ][INFO    ][7867] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'boot_interface': {u'name': u'eno1', 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': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], 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': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qndbm6', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/interfaces/5/'}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'storage_test_status_name': u'Passed', u'owner_data': {}, 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'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qndbm6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'5f5198c9-add3-4646-829d-eca48f5cef06', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qndbm6', u'device_id': 2, u'filesystem': {u'uuid': u'a0755d72-9e53-4d39-be34-2e95d8e385bf', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'disable_ipv4': False, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'qndbm6', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'1134aa97-fb72-47ea-b22b-a8513ab1747a', u'id': 12, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'qndbm6', u'partition_table_type': None, u'filesystem': {u'uuid': u'15cb2fd1-c7e4-4348-a52e-50ed06ddca41', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'uuid': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qndbm6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'5f5198c9-add3-4646-829d-eca48f5cef06', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qndbm6', u'device_id': 2, u'filesystem': {u'uuid': u'a0755d72-9e53-4d39-be34-2e95d8e385bf', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'uuid': u'1134aa97-fb72-47ea-b22b-a8513ab1747a', u'id': 12, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'qndbm6', u'partition_table_type': None, u'filesystem': {u'uuid': u'15cb2fd1-c7e4-4348-a52e-50ed06ddca41', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qndbm6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'5f5198c9-add3-4646-829d-eca48f5cef06', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qndbm6', u'device_id': 2, u'filesystem': {u'uuid': u'a0755d72-9e53-4d39-be34-2e95d8e385bf', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qndbm6', 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'storage_test_status': 2, u'current_commissioning_result_id': 4, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', 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': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], 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': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qndbm6', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qndbm6', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/interfaces/16/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qndbm6', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], 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'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'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qndbm6', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], 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'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'fabric': u'fabric-0'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qndbm6', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/interfaces/19/'}, {u'name': u'eno2', 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': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], 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': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.41'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'qndbm6', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/qndbm6/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/qndbm6/'}
2019-06-27 02:15:07,331 [salt.state       :300 ][INFO    ][7867] {'new': {'storage_layout': 'lvm'}}
2019-06-27 02:15:07,332 [salt.state       :1951][INFO    ][7867] Completed state [maas_machines_storage_cmp001_lvm] at time 02:15:07.332033 duration_in_ms=2427.374
2019-06-27 02:15:07,337 [salt.minion      :1711][INFO    ][7867] Returning information for job: 20190627021451761670
2019-06-27 02:15:08,072 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627021508057092
2019-06-27 02:15:08,099 [salt.minion      :1432][INFO    ][7938] Starting a new job with PID 7938
2019-06-27 02:15:09,476 [salt.state       :915 ][INFO    ][7938] Loading fresh modules for state activity
2019-06-27 02:15:09,539 [salt.fileclient  :1219][INFO    ][7938] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-27 02:15:09,597 [salt.state       :1780][INFO    ][7938] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:09.597515
2019-06-27 02:15:09,598 [salt.state       :1813][INFO    ][7938] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-27 02:15:09,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7938] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-27 02:15:11,462 [salt.state       :300 ][INFO    ][7938] {'pid': 7945, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-27 02:15:11,463 [salt.state       :1951][INFO    ][7938] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:11.463204 duration_in_ms=1865.689
2019-06-27 02:15:11,466 [salt.state       :1780][INFO    ][7938] Running state [maas.deploy_machines] at time 02:15:11.466792
2019-06-27 02:15:11,467 [salt.state       :1813][INFO    ][7938] Executing state module.run for [maas.deploy_machines]
2019-06-27 02:15:11,468 [salt.utils.decorators:613 ][WARNING ][7938] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:15:12,058 [salt.loaded.ext.module.maas:684 ][INFO    ][7938] deploymachines hwe_kernel=ga-16.04 system_id=gecfx7 distro_series=xenial
2019-06-27 02:15:14,752 [salt.loaded.ext.module.maas:684 ][INFO    ][7938] deploymachines hwe_kernel=ga-16.04 system_id=qndbm6 distro_series=xenial
2019-06-27 02:15:17,266 [salt.loaded.ext.module.maas:684 ][INFO    ][7938] deploymachines hwe_kernel=ga-16.04 system_id=gdh7eh distro_series=xenial
2019-06-27 02:15:19,824 [salt.loaded.ext.module.maas:684 ][INFO    ][7938] deploymachines hwe_kernel=ga-16.04 system_id=4dftg3 distro_series=xenial
2019-06-27 02:15:22,327 [salt.loaded.ext.module.maas:684 ][INFO    ][7938] deploymachines hwe_kernel=ga-16.04 system_id=xmcwna distro_series=xenial
2019-06-27 02:15:23,111 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021523098974
2019-06-27 02:15:23,136 [salt.minion      :1432][INFO    ][8198] Starting a new job with PID 8198
2019-06-27 02:15:23,169 [salt.minion      :1711][INFO    ][8198] Returning information for job: 20190627021523098974
2019-06-27 02:15:24,908 [salt.state       :300 ][INFO    ][7938] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-27 02:15:24,908 [salt.state       :1951][INFO    ][7938] Completed state [maas.deploy_machines] at time 02:15:24.908551 duration_in_ms=13441.759
2019-06-27 02:15:24,911 [salt.minion      :1711][INFO    ][7938] Returning information for job: 20190627021508057092
2019-06-27 02:15:25,637 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627021525625317
2019-06-27 02:15:25,662 [salt.minion      :1432][INFO    ][8247] Starting a new job with PID 8247
2019-06-27 02:15:34,097 [salt.state       :915 ][INFO    ][8247] Loading fresh modules for state activity
2019-06-27 02:15:34,163 [salt.fileclient  :1219][INFO    ][8247] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-27 02:15:34,227 [salt.state       :1780][INFO    ][8247] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:34.226988
2019-06-27 02:15:34,227 [salt.state       :1813][INFO    ][8247] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-27 02:15:34,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8247] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-27 02:15:36,164 [salt.state       :300 ][INFO    ][8247] {'pid': 8269, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-27 02:15:36,166 [salt.state       :1951][INFO    ][8247] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:36.165700 duration_in_ms=1938.713
2019-06-27 02:15:36,171 [salt.state       :1780][INFO    ][8247] Running state [maas.wait_for_machine_status] at time 02:15:36.171727
2019-06-27 02:15:36,172 [salt.state       :1813][INFO    ][8247] Executing state module.run for [maas.wait_for_machine_status]
2019-06-27 02:15:36,172 [salt.utils.decorators:613 ][WARNING ][8247] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:15:39,276 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.9080801s left)
2019-06-27 02:15:40,744 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021540729110
2019-06-27 02:15:40,776 [salt.minion      :1432][INFO    ][8281] Starting a new job with PID 8281
2019-06-27 02:15:40,810 [salt.minion      :1711][INFO    ][8281] Returning information for job: 20190627021540729110
2019-06-27 02:16:10,847 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021610839015
2019-06-27 02:16:10,866 [salt.minion      :1432][INFO    ][8308] Starting a new job with PID 8308
2019-06-27 02:16:10,902 [salt.minion      :1711][INFO    ][8308] Returning information for job: 20190627021610839015
2019-06-27 02:16:12,235 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.94918299s left)
2019-06-27 02:16:40,990 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021640973664
2019-06-27 02:16:41,020 [salt.minion      :1432][INFO    ][8354] Starting a new job with PID 8354
2019-06-27 02:16:41,054 [salt.minion      :1711][INFO    ][8354] Returning information for job: 20190627021640973664
2019-06-27 02:16:45,178 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.00648904s left)
2019-06-27 02:17:11,076 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021711064258
2019-06-27 02:17:11,099 [salt.minion      :1432][INFO    ][8400] Starting a new job with PID 8400
2019-06-27 02:17:11,145 [salt.minion      :1711][INFO    ][8400] Returning information for job: 20190627021711064258
2019-06-27 02:17:18,416 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.76825905s left)
2019-06-27 02:17:41,171 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021741156852
2019-06-27 02:17:41,201 [salt.minion      :1432][INFO    ][8463] Starting a new job with PID 8463
2019-06-27 02:17:41,230 [salt.minion      :1711][INFO    ][8463] Returning information for job: 20190627021741156852
2019-06-27 02:17:51,210 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.97381496s left)
2019-06-27 02:18:11,286 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021811273446
2019-06-27 02:18:11,313 [salt.minion      :1432][INFO    ][8552] Starting a new job with PID 8552
2019-06-27 02:18:11,348 [salt.minion      :1711][INFO    ][8552] Returning information for job: 20190627021811273446
2019-06-27 02:18:24,157 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.02772212s left)
2019-06-27 02:18:41,422 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021841405273
2019-06-27 02:18:41,448 [salt.minion      :1432][INFO    ][8690] Starting a new job with PID 8690
2019-06-27 02:18:41,484 [salt.minion      :1711][INFO    ][8690] Returning information for job: 20190627021841405273
2019-06-27 02:18:57,198 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.98606706s left)
2019-06-27 02:19:11,548 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021911523142
2019-06-27 02:19:11,575 [salt.minion      :1432][INFO    ][8725] Starting a new job with PID 8725
2019-06-27 02:19:11,605 [salt.minion      :1711][INFO    ][8725] Returning information for job: 20190627021911523142
2019-06-27 02:19:30,366 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.81803012s left)
2019-06-27 02:19:41,687 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627021941670191
2019-06-27 02:19:41,719 [salt.minion      :1432][INFO    ][9076] Starting a new job with PID 9076
2019-06-27 02:19:41,749 [salt.minion      :1711][INFO    ][9076] Returning information for job: 20190627021941670191
2019-06-27 02:20:03,424 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.76024699s left)
2019-06-27 02:20:11,818 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022011804034
2019-06-27 02:20:11,849 [salt.minion      :1432][INFO    ][9165] Starting a new job with PID 9165
2019-06-27 02:20:11,884 [salt.minion      :1711][INFO    ][9165] Returning information for job: 20190627022011804034
2019-06-27 02:20:36,471 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.71306705s left)
2019-06-27 02:20:42,015 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022041996469
2019-06-27 02:20:42,043 [salt.minion      :1432][INFO    ][9407] Starting a new job with PID 9407
2019-06-27 02:20:42,073 [salt.minion      :1711][INFO    ][9407] Returning information for job: 20190627022041996469
2019-06-27 02:21:09,455 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.72948003s left)
2019-06-27 02:21:12,151 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022112138312
2019-06-27 02:21:12,167 [salt.minion      :1432][INFO    ][9436] Starting a new job with PID 9436
2019-06-27 02:21:12,198 [salt.minion      :1711][INFO    ][9436] Returning information for job: 20190627022112138312
2019-06-27 02:21:42,309 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022142295274
2019-06-27 02:21:42,331 [salt.minion      :1432][INFO    ][9797] Starting a new job with PID 9797
2019-06-27 02:21:42,360 [salt.minion      :1711][INFO    ][9797] Returning information for job: 20190627022142295274
2019-06-27 02:21:42,624 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.56060505s left)
2019-06-27 02:22:12,453 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022212440511
2019-06-27 02:22:12,480 [salt.minion      :1432][INFO    ][9842] Starting a new job with PID 9842
2019-06-27 02:22:12,515 [salt.minion      :1711][INFO    ][9842] Returning information for job: 20190627022212440511
2019-06-27 02:22:17,406 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1848.77844715s left)
2019-06-27 02:22:42,656 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022242639180
2019-06-27 02:22:42,689 [salt.minion      :1432][INFO    ][10234] Starting a new job with PID 10234
2019-06-27 02:22:42,716 [salt.minion      :1711][INFO    ][10234] Returning information for job: 20190627022242639180
2019-06-27 02:22:50,395 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1815.78918219s left)
2019-06-27 02:23:12,804 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022312794879
2019-06-27 02:23:12,833 [salt.minion      :1432][INFO    ][10270] Starting a new job with PID 10270
2019-06-27 02:23:12,863 [salt.minion      :1711][INFO    ][10270] Returning information for job: 20190627022312794879
2019-06-27 02:23:23,388 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1782.79611397s left)
2019-06-27 02:23:42,973 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022342966206
2019-06-27 02:23:42,999 [salt.minion      :1432][INFO    ][10457] Starting a new job with PID 10457
2019-06-27 02:23:43,028 [salt.minion      :1711][INFO    ][10457] Returning information for job: 20190627022342966206
2019-06-27 02:23:56,264 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1749.92058897s left)
2019-06-27 02:24:13,165 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022413147444
2019-06-27 02:24:13,196 [salt.minion      :1432][INFO    ][10493] Starting a new job with PID 10493
2019-06-27 02:24:13,225 [salt.minion      :1711][INFO    ][10493] Returning information for job: 20190627022413147444
2019-06-27 02:24:29,130 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.05430913s left)
2019-06-27 02:24:43,335 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022443319001
2019-06-27 02:24:43,366 [salt.minion      :1432][INFO    ][10629] Starting a new job with PID 10629
2019-06-27 02:24:43,399 [salt.minion      :1711][INFO    ][10629] Returning information for job: 20190627022443319001
2019-06-27 02:25:01,952 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.23230696s left)
2019-06-27 02:25:13,416 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022513401411
2019-06-27 02:25:13,446 [salt.minion      :1432][INFO    ][10706] Starting a new job with PID 10706
2019-06-27 02:25:13,482 [salt.minion      :1711][INFO    ][10706] Returning information for job: 20190627022513401411
2019-06-27 02:25:34,764 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.41989207s left)
2019-06-27 02:25:43,625 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022543607546
2019-06-27 02:25:43,661 [salt.minion      :1432][INFO    ][10932] Starting a new job with PID 10932
2019-06-27 02:25:43,687 [salt.minion      :1711][INFO    ][10932] Returning information for job: 20190627022543607546
2019-06-27 02:26:07,967 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.21740818s left)
2019-06-27 02:26:13,816 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022613805564
2019-06-27 02:26:13,835 [salt.minion      :1432][INFO    ][10961] Starting a new job with PID 10961
2019-06-27 02:26:13,870 [salt.minion      :1711][INFO    ][10961] Returning information for job: 20190627022613805564
2019-06-27 02:26:40,732 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.45247412s left)
2019-06-27 02:26:44,008 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022643999334
2019-06-27 02:26:44,026 [salt.minion      :1432][INFO    ][11090] Starting a new job with PID 11090
2019-06-27 02:26:44,057 [salt.minion      :1711][INFO    ][11090] Returning information for job: 20190627022643999334
2019-06-27 02:27:13,521 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm01', 'kvm02']
sleep for:30s Timeout:2250s (1552.66359496s left)
2019-06-27 02:27:14,038 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022714015431
2019-06-27 02:27:14,064 [salt.minion      :1432][INFO    ][11312] Starting a new job with PID 11312
2019-06-27 02:27:14,121 [salt.minion      :1711][INFO    ][11312] Returning information for job: 20190627022714015431
2019-06-27 02:27:44,080 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022744064598
2019-06-27 02:27:44,099 [salt.minion      :1432][INFO    ][11482] Starting a new job with PID 11482
2019-06-27 02:27:44,132 [salt.minion      :1711][INFO    ][11482] Returning information for job: 20190627022744064598
2019-06-27 02:27:46,356 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm01', 'kvm02']
sleep for:30s Timeout:2250s (1519.82862902s left)
2019-06-27 02:28:14,242 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022814221650
2019-06-27 02:28:14,269 [salt.minion      :1432][INFO    ][11576] Starting a new job with PID 11576
2019-06-27 02:28:14,327 [salt.minion      :1711][INFO    ][11576] Returning information for job: 20190627022814221650
2019-06-27 02:28:19,486 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1486.69911313s left)
2019-06-27 02:28:44,292 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022844283910
2019-06-27 02:28:44,316 [salt.minion      :1432][INFO    ][11663] Starting a new job with PID 11663
2019-06-27 02:28:44,348 [salt.minion      :1711][INFO    ][11663] Returning information for job: 20190627022844283910
2019-06-27 02:28:52,318 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.86623096s left)
2019-06-27 02:29:14,323 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022914305310
2019-06-27 02:29:14,350 [salt.minion      :1432][INFO    ][11751] Starting a new job with PID 11751
2019-06-27 02:29:14,388 [salt.minion      :1711][INFO    ][11751] Returning information for job: 20190627022914305310
2019-06-27 02:29:25,305 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.8797071s left)
2019-06-27 02:29:44,380 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627022944364382
2019-06-27 02:29:44,408 [salt.minion      :1432][INFO    ][11804] Starting a new job with PID 11804
2019-06-27 02:29:44,436 [salt.minion      :1711][INFO    ][11804] Returning information for job: 20190627022944364382
2019-06-27 02:29:58,178 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1388.00623703s left)
2019-06-27 02:30:14,411 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023014403009
2019-06-27 02:30:14,435 [salt.minion      :1432][INFO    ][11847] Starting a new job with PID 11847
2019-06-27 02:30:14,465 [salt.minion      :1711][INFO    ][11847] Returning information for job: 20190627023014403009
2019-06-27 02:30:31,069 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.11514616s left)
2019-06-27 02:30:44,479 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023044461451
2019-06-27 02:30:44,506 [salt.minion      :1432][INFO    ][11881] Starting a new job with PID 11881
2019-06-27 02:30:44,539 [salt.minion      :1711][INFO    ][11881] Returning information for job: 20190627023044461451
2019-06-27 02:31:03,961 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1322.22312117s left)
2019-06-27 02:31:14,548 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023114539217
2019-06-27 02:31:14,567 [salt.minion      :1432][INFO    ][11933] Starting a new job with PID 11933
2019-06-27 02:31:14,605 [salt.minion      :1711][INFO    ][11933] Returning information for job: 20190627023114539217
2019-06-27 02:31:36,910 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.27453399s left)
2019-06-27 02:31:44,636 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023144622946
2019-06-27 02:31:44,665 [salt.minion      :1432][INFO    ][11959] Starting a new job with PID 11959
2019-06-27 02:31:44,699 [salt.minion      :1711][INFO    ][11959] Returning information for job: 20190627023144622946
2019-06-27 02:32:09,701 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.48312616s left)
2019-06-27 02:32:14,740 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023214725602
2019-06-27 02:32:14,760 [salt.minion      :1432][INFO    ][12020] Starting a new job with PID 12020
2019-06-27 02:32:14,795 [salt.minion      :1711][INFO    ][12020] Returning information for job: 20190627023214725602
2019-06-27 02:32:42,453 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.73159719s left)
2019-06-27 02:32:44,820 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023244811500
2019-06-27 02:32:44,851 [salt.minion      :1432][INFO    ][12045] Starting a new job with PID 12045
2019-06-27 02:32:44,880 [salt.minion      :1711][INFO    ][12045] Returning information for job: 20190627023244811500
2019-06-27 02:33:14,937 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023314926382
2019-06-27 02:33:14,963 [salt.minion      :1432][INFO    ][12100] Starting a new job with PID 12100
2019-06-27 02:33:14,999 [salt.minion      :1711][INFO    ][12100] Returning information for job: 20190627023314926382
2019-06-27 02:33:15,504 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.6803391s left)
2019-06-27 02:33:45,089 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023345073268
2019-06-27 02:33:45,107 [salt.minion      :1432][INFO    ][12122] Starting a new job with PID 12122
2019-06-27 02:33:45,139 [salt.minion      :1711][INFO    ][12122] Returning information for job: 20190627023345073268
2019-06-27 02:33:48,226 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.95779705s left)
2019-06-27 02:34:15,246 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023415228975
2019-06-27 02:34:15,274 [salt.minion      :1432][INFO    ][12176] Starting a new job with PID 12176
2019-06-27 02:34:15,307 [salt.minion      :1711][INFO    ][12176] Returning information for job: 20190627023415228975
2019-06-27 02:34:21,071 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1125.11368513s left)
2019-06-27 02:34:45,397 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023445382338
2019-06-27 02:34:45,427 [salt.minion      :1432][INFO    ][12203] Starting a new job with PID 12203
2019-06-27 02:34:45,462 [salt.minion      :1711][INFO    ][12203] Returning information for job: 20190627023445382338
2019-06-27 02:34:53,924 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1092.26017714s left)
2019-06-27 02:35:15,611 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023515587829
2019-06-27 02:35:15,639 [salt.minion      :1432][INFO    ][12257] Starting a new job with PID 12257
2019-06-27 02:35:15,676 [salt.minion      :1711][INFO    ][12257] Returning information for job: 20190627023515587829
2019-06-27 02:35:26,743 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1059.44087195s left)
2019-06-27 02:35:45,781 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023545763823
2019-06-27 02:35:45,812 [salt.minion      :1432][INFO    ][12282] Starting a new job with PID 12282
2019-06-27 02:35:45,854 [salt.minion      :1711][INFO    ][12282] Returning information for job: 20190627023545763823
2019-06-27 02:35:59,807 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1026.37719417s left)
2019-06-27 02:36:15,994 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023615985075
2019-06-27 02:36:16,015 [salt.minion      :1432][INFO    ][12338] Starting a new job with PID 12338
2019-06-27 02:36:16,044 [salt.minion      :1711][INFO    ][12338] Returning information for job: 20190627023615985075
2019-06-27 02:36:32,646 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.538521051s left)
2019-06-27 02:36:46,204 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023646182654
2019-06-27 02:36:46,232 [salt.minion      :1432][INFO    ][12361] Starting a new job with PID 12361
2019-06-27 02:36:46,264 [salt.minion      :1711][INFO    ][12361] Returning information for job: 20190627023646182654
2019-06-27 02:37:05,394 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.789990187s left)
2019-06-27 02:37:16,234 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023716221494
2019-06-27 02:37:16,265 [salt.minion      :1432][INFO    ][12434] Starting a new job with PID 12434
2019-06-27 02:37:16,309 [salt.minion      :1711][INFO    ][12434] Returning information for job: 20190627023716221494
2019-06-27 02:37:38,155 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (928.029700994s left)
2019-06-27 02:37:46,280 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023746268711
2019-06-27 02:37:46,311 [salt.minion      :1432][INFO    ][12466] Starting a new job with PID 12466
2019-06-27 02:37:46,345 [salt.minion      :1711][INFO    ][12466] Returning information for job: 20190627023746268711
2019-06-27 02:38:10,882 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (895.30232501s left)
2019-06-27 02:38:16,348 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023816330747
2019-06-27 02:38:16,375 [salt.minion      :1432][INFO    ][12521] Starting a new job with PID 12521
2019-06-27 02:38:16,408 [salt.minion      :1711][INFO    ][12521] Returning information for job: 20190627023816330747
2019-06-27 02:38:43,787 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (862.39745307s left)
2019-06-27 02:38:46,428 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023846411798
2019-06-27 02:38:46,462 [salt.minion      :1432][INFO    ][12541] Starting a new job with PID 12541
2019-06-27 02:38:46,500 [salt.minion      :1711][INFO    ][12541] Returning information for job: 20190627023846411798
2019-06-27 02:39:16,518 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023916505141
2019-06-27 02:39:16,540 [salt.minion      :1432][INFO    ][12597] Starting a new job with PID 12597
2019-06-27 02:39:16,570 [salt.minion      :1711][INFO    ][12597] Returning information for job: 20190627023916505141
2019-06-27 02:39:16,997 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.187003136s left)
2019-06-27 02:39:46,650 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627023946634828
2019-06-27 02:39:46,680 [salt.minion      :1432][INFO    ][12615] Starting a new job with PID 12615
2019-06-27 02:39:46,712 [salt.minion      :1711][INFO    ][12615] Returning information for job: 20190627023946634828
2019-06-27 02:39:49,848 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (796.336084127s left)
2019-06-27 02:40:16,747 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024016731528
2019-06-27 02:40:16,777 [salt.minion      :1432][INFO    ][12669] Starting a new job with PID 12669
2019-06-27 02:40:16,809 [salt.minion      :1711][INFO    ][12669] Returning information for job: 20190627024016731528
2019-06-27 02:40:22,579 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (763.605188131s left)
2019-06-27 02:40:46,907 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024046890315
2019-06-27 02:40:46,936 [salt.minion      :1432][INFO    ][12692] Starting a new job with PID 12692
2019-06-27 02:40:46,970 [salt.minion      :1711][INFO    ][12692] Returning information for job: 20190627024046890315
2019-06-27 02:40:55,324 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (730.860340118s left)
2019-06-27 02:41:17,060 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024117047174
2019-06-27 02:41:17,088 [salt.minion      :1432][INFO    ][12751] Starting a new job with PID 12751
2019-06-27 02:41:17,120 [salt.minion      :1711][INFO    ][12751] Returning information for job: 20190627024117047174
2019-06-27 02:41:28,228 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.956380129s left)
2019-06-27 02:41:47,227 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024147211313
2019-06-27 02:41:47,256 [salt.minion      :1432][INFO    ][12770] Starting a new job with PID 12770
2019-06-27 02:41:47,288 [salt.minion      :1711][INFO    ][12770] Returning information for job: 20190627024147211313
2019-06-27 02:42:01,077 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (665.107499123s left)
2019-06-27 02:42:17,386 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024217370739
2019-06-27 02:42:17,412 [salt.minion      :1432][INFO    ][12830] Starting a new job with PID 12830
2019-06-27 02:42:17,444 [salt.minion      :1711][INFO    ][12830] Returning information for job: 20190627024217370739
2019-06-27 02:42:33,869 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (632.315467119s left)
2019-06-27 02:42:47,596 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024247580496
2019-06-27 02:42:47,624 [salt.minion      :1432][INFO    ][12850] Starting a new job with PID 12850
2019-06-27 02:42:47,660 [salt.minion      :1711][INFO    ][12850] Returning information for job: 20190627024247580496
2019-06-27 02:43:06,572 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (599.612308979s left)
2019-06-27 02:43:17,792 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024317774816
2019-06-27 02:43:17,825 [salt.minion      :1432][INFO    ][12908] Starting a new job with PID 12908
2019-06-27 02:43:17,855 [salt.minion      :1711][INFO    ][12908] Returning information for job: 20190627024317774816
2019-06-27 02:43:39,339 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (566.844961166s left)
2019-06-27 02:43:47,825 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024347808725
2019-06-27 02:43:47,850 [salt.minion      :1432][INFO    ][12929] Starting a new job with PID 12929
2019-06-27 02:43:47,886 [salt.minion      :1711][INFO    ][12929] Returning information for job: 20190627024347808725
2019-06-27 02:44:12,152 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (534.032201052s left)
2019-06-27 02:44:17,852 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024417839728
2019-06-27 02:44:17,877 [salt.minion      :1432][INFO    ][12986] Starting a new job with PID 12986
2019-06-27 02:44:17,905 [salt.minion      :1711][INFO    ][12986] Returning information for job: 20190627024417839728
2019-06-27 02:44:44,923 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (501.26108408s left)
2019-06-27 02:44:47,889 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024447874982
2019-06-27 02:44:47,916 [salt.minion      :1432][INFO    ][13009] Starting a new job with PID 13009
2019-06-27 02:44:47,941 [salt.minion      :1711][INFO    ][13009] Returning information for job: 20190627024447874982
2019-06-27 02:45:17,848 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (468.33594203s left)
2019-06-27 02:45:17,993 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024517980102
2019-06-27 02:45:18,019 [salt.minion      :1432][INFO    ][13065] Starting a new job with PID 13065
2019-06-27 02:45:18,043 [salt.minion      :1711][INFO    ][13065] Returning information for job: 20190627024517980102
2019-06-27 02:45:48,090 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024548078790
2019-06-27 02:45:48,113 [salt.minion      :1432][INFO    ][13082] Starting a new job with PID 13082
2019-06-27 02:45:48,137 [salt.minion      :1711][INFO    ][13082] Returning information for job: 20190627024548078790
2019-06-27 02:45:50,740 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (435.444527149s left)
2019-06-27 02:46:18,260 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024618247695
2019-06-27 02:46:18,289 [salt.minion      :1432][INFO    ][13142] Starting a new job with PID 13142
2019-06-27 02:46:18,317 [salt.minion      :1711][INFO    ][13142] Returning information for job: 20190627024618247695
2019-06-27 02:46:23,500 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (402.683927059s left)
2019-06-27 02:46:48,347 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024648331461
2019-06-27 02:46:48,377 [salt.minion      :1432][INFO    ][13159] Starting a new job with PID 13159
2019-06-27 02:46:48,401 [salt.minion      :1711][INFO    ][13159] Returning information for job: 20190627024648331461
2019-06-27 02:46:56,537 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (369.647655964s left)
2019-06-27 02:47:18,508 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024718494171
2019-06-27 02:47:18,536 [salt.minion      :1432][INFO    ][13356] Starting a new job with PID 13356
2019-06-27 02:47:18,558 [salt.minion      :1711][INFO    ][13356] Returning information for job: 20190627024718494171
2019-06-27 02:47:29,214 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (336.97104001s left)
2019-06-27 02:47:48,685 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024748667833
2019-06-27 02:47:48,709 [salt.minion      :1432][INFO    ][13380] Starting a new job with PID 13380
2019-06-27 02:47:48,734 [salt.minion      :1711][INFO    ][13380] Returning information for job: 20190627024748667833
2019-06-27 02:48:01,980 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (304.204513073s left)
2019-06-27 02:48:18,703 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024818689845
2019-06-27 02:48:18,729 [salt.minion      :1432][INFO    ][13439] Starting a new job with PID 13439
2019-06-27 02:48:18,751 [salt.minion      :1711][INFO    ][13439] Returning information for job: 20190627024818689845
2019-06-27 02:48:35,072 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (271.112750053s left)
2019-06-27 02:48:48,904 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024848888171
2019-06-27 02:48:48,933 [salt.minion      :1432][INFO    ][13459] Starting a new job with PID 13459
2019-06-27 02:48:48,956 [salt.minion      :1711][INFO    ][13459] Returning information for job: 20190627024848888171
2019-06-27 02:49:07,976 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (238.208743095s left)
2019-06-27 02:49:18,929 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024918913293
2019-06-27 02:49:18,957 [salt.minion      :1432][INFO    ][13524] Starting a new job with PID 13524
2019-06-27 02:49:18,977 [salt.minion      :1711][INFO    ][13524] Returning information for job: 20190627024918913293
2019-06-27 02:49:40,720 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (205.464245081s left)
2019-06-27 02:49:49,159 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627024949141614
2019-06-27 02:49:49,185 [salt.minion      :1432][INFO    ][13545] Starting a new job with PID 13545
2019-06-27 02:49:49,209 [salt.minion      :1711][INFO    ][13545] Returning information for job: 20190627024949141614
2019-06-27 02:50:13,694 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (172.490098953s left)
2019-06-27 02:50:19,255 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025019238926
2019-06-27 02:50:19,282 [salt.minion      :1432][INFO    ][13600] Starting a new job with PID 13600
2019-06-27 02:50:19,307 [salt.minion      :1711][INFO    ][13600] Returning information for job: 20190627025019238926
2019-06-27 02:50:46,401 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (139.782922983s left)
2019-06-27 02:50:49,362 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025049349534
2019-06-27 02:50:49,380 [salt.minion      :1432][INFO    ][13621] Starting a new job with PID 13621
2019-06-27 02:50:49,406 [salt.minion      :1711][INFO    ][13621] Returning information for job: 20190627025049349534
2019-06-27 02:51:19,120 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (107.064422131s left)
2019-06-27 02:51:19,411 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025119394558
2019-06-27 02:51:19,439 [salt.minion      :1432][INFO    ][13677] Starting a new job with PID 13677
2019-06-27 02:51:19,465 [salt.minion      :1711][INFO    ][13677] Returning information for job: 20190627025119394558
2019-06-27 02:51:49,576 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025149563062
2019-06-27 02:51:49,596 [salt.minion      :1432][INFO    ][13692] Starting a new job with PID 13692
2019-06-27 02:51:49,620 [salt.minion      :1711][INFO    ][13692] Returning information for job: 20190627025149563062
2019-06-27 02:51:52,110 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (74.0740721226s left)
2019-06-27 02:52:19,749 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025219738839
2019-06-27 02:52:19,783 [salt.minion      :1432][INFO    ][13751] Starting a new job with PID 13751
2019-06-27 02:52:19,805 [salt.minion      :1711][INFO    ][13751] Returning information for job: 20190627025219738839
2019-06-27 02:52:24,862 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (41.3223061562s left)
2019-06-27 02:52:49,912 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025249894903
2019-06-27 02:52:49,940 [salt.minion      :1432][INFO    ][13769] Starting a new job with PID 13769
2019-06-27 02:52:49,962 [salt.minion      :1711][INFO    ][13769] Returning information for job: 20190627025249894903
2019-06-27 02:52:57,713 [salt.loaded.ext.module.maas:1023][INFO    ][8247] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (8.47156596184s left)
2019-06-27 02:53:20,023 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025320011931
2019-06-27 02:53:20,051 [salt.minion      :1432][INFO    ][13825] Starting a new job with PID 13825
2019-06-27 02:53:20,073 [salt.minion      :1711][INFO    ][13825] Returning information for job: 20190627025320011931
2019-06-27 02:53:30,646 [salt.state       :302 ][ERROR   ][8247] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-27 02:53:30,647 [salt.state       :1951][INFO    ][8247] Completed state [maas.wait_for_machine_status] at time 02:53:30.647249 duration_in_ms=2274475.515
2019-06-27 02:53:30,655 [salt.minion      :1711][INFO    ][8247] Returning information for job: 20190627021525625317
2019-06-27 02:53:41,469 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command pillar.get with jid 20190627025341454834
2019-06-27 02:53:41,499 [salt.minion      :1432][INFO    ][13846] Starting a new job with PID 13846
2019-06-27 02:53:41,511 [salt.minion      :1711][INFO    ][13846] Returning information for job: 20190627025341454834
2019-06-27 02:53:42,089 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command service.status with jid 20190627025342073099
2019-06-27 02:53:42,117 [salt.minion      :1432][INFO    ][13853] Starting a new job with PID 13853
2019-06-27 02:53:42,828 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13853] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-27 02:53:42,875 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13853] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-27 02:53:42,898 [salt.minion      :1711][INFO    ][13853] Returning information for job: 20190627025342073099
2019-06-27 02:53:43,465 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627025343453127
2019-06-27 02:53:43,496 [salt.minion      :1432][INFO    ][13864] Starting a new job with PID 13864
2019-06-27 02:53:51,679 [salt.state       :915 ][INFO    ][13864] Loading fresh modules for state activity
2019-06-27 02:53:52,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command 'salt-minion --version' in directory '/root'
2019-06-27 02:53:52,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command 'salt-minion --version' in directory '/root'
2019-06-27 02:53:53,649 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command 'salt-minion --version' in directory '/root'
2019-06-27 02:53:53,948 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command 'salt-minion --version' in directory '/root'
2019-06-27 02:53:55,860 [salt.state       :1780][INFO    ][13864] Running state [salt-minion] at time 02:53:55.860250
2019-06-27 02:53:55,860 [salt.state       :1813][INFO    ][13864] Executing state pkg.installed for [salt-minion]
2019-06-27 02:53:55,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-27 02:53:55,972 [salt.state       :300 ][INFO    ][13864] All specified packages are already installed
2019-06-27 02:53:55,972 [salt.state       :1951][INFO    ][13864] Completed state [salt-minion] at time 02:53:55.972713 duration_in_ms=112.463
2019-06-27 02:53:55,973 [salt.state       :1780][INFO    ][13864] Running state [salt_minion_dependency_packages] at time 02:53:55.973057
2019-06-27 02:53:55,973 [salt.state       :1813][INFO    ][13864] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-27 02:53:55,982 [salt.state       :300 ][INFO    ][13864] All specified packages are already installed
2019-06-27 02:53:55,983 [salt.state       :1951][INFO    ][13864] Completed state [salt_minion_dependency_packages] at time 02:53:55.983073 duration_in_ms=10.016
2019-06-27 02:53:55,986 [salt.state       :1780][INFO    ][13864] Running state [/etc/salt/minion.d/minion.conf] at time 02:53:55.986517
2019-06-27 02:53:55,986 [salt.state       :1813][INFO    ][13864] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-27 02:53:56,326 [salt.state       :300 ][INFO    ][13864] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-27 02:53:56,326 [salt.state       :1951][INFO    ][13864] Completed state [/etc/salt/minion.d/minion.conf] at time 02:53:56.326420 duration_in_ms=339.903
2019-06-27 02:53:56,327 [salt.state       :1780][INFO    ][13864] Running state [python-netaddr] at time 02:53:56.327169
2019-06-27 02:53:56,327 [salt.state       :1813][INFO    ][13864] Executing state pkg.installed for [python-netaddr]
2019-06-27 02:53:56,343 [salt.state       :300 ][INFO    ][13864] All specified packages are already installed
2019-06-27 02:53:56,343 [salt.state       :1951][INFO    ][13864] Completed state [python-netaddr] at time 02:53:56.343690 duration_in_ms=16.522
2019-06-27 02:53:56,347 [salt.state       :1780][INFO    ][13864] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:56.347509
2019-06-27 02:53:56,347 [salt.state       :1813][INFO    ][13864] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-27 02:53:56,361 [salt.state       :300 ][INFO    ][13864] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-27 02:53:56,362 [salt.state       :1951][INFO    ][13864] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:53:56.362048 duration_in_ms=14.538
2019-06-27 02:53:56,363 [salt.state       :1780][INFO    ][13864] Running state [salt-minion] at time 02:53:56.363318
2019-06-27 02:53:56,363 [salt.state       :1813][INFO    ][13864] Executing state service.running for [salt-minion]
2019-06-27 02:53:56,364 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-27 02:53:56,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-27 02:53:56,430 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-27 02:53:56,453 [salt.state       :300 ][INFO    ][13864] The service salt-minion is already running
2019-06-27 02:53:56,453 [salt.state       :1951][INFO    ][13864] Completed state [salt-minion] at time 02:53:56.453771 duration_in_ms=90.453
2019-06-27 02:53:56,456 [salt.state       :1780][INFO    ][13864] Running state [/etc/salt/grains.d] at time 02:53:56.456170
2019-06-27 02:53:56,456 [salt.state       :1813][INFO    ][13864] Executing state file.directory for [/etc/salt/grains.d]
2019-06-27 02:53:56,458 [salt.state       :300 ][INFO    ][13864] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-27 02:53:56,458 [salt.state       :1951][INFO    ][13864] Completed state [/etc/salt/grains.d] at time 02:53:56.458394 duration_in_ms=2.225
2019-06-27 02:53:56,459 [salt.state       :1780][INFO    ][13864] Running state [/etc/salt/grains] at time 02:53:56.459422
2019-06-27 02:53:56,459 [salt.state       :1813][INFO    ][13864] Executing state file.managed for [/etc/salt/grains]
2019-06-27 02:53:56,460 [salt.state       :300 ][INFO    ][13864] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-27 02:53:56,460 [salt.state       :1951][INFO    ][13864] Completed state [/etc/salt/grains] at time 02:53:56.460920 duration_in_ms=1.498
2019-06-27 02:53:56,463 [salt.state       :1780][INFO    ][13864] Running state [/etc/salt/grains.d/placeholder] at time 02:53:56.461641
2019-06-27 02:53:56,464 [salt.state       :1813][INFO    ][13864] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-27 02:53:56,464 [salt.state       :300 ][INFO    ][13864] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-27 02:53:56,464 [salt.state       :1951][INFO    ][13864] Completed state [/etc/salt/grains.d/placeholder] at time 02:53:56.464881 duration_in_ms=3.24
2019-06-27 02:53:56,465 [salt.state       :1780][INFO    ][13864] Running state [/etc/salt/grains.d/sphinx] at time 02:53:56.465369
2019-06-27 02:53:56,465 [salt.state       :1813][INFO    ][13864] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-27 02:53:56,466 [salt.state       :300 ][INFO    ][13864] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-27 02:53:56,467 [salt.state       :1951][INFO    ][13864] Completed state [/etc/salt/grains.d/sphinx] at time 02:53:56.466953 duration_in_ms=1.584
2019-06-27 02:53:56,469 [salt.state       :1780][INFO    ][13864] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:56.468998
2019-06-27 02:53:56,469 [salt.state       :1813][INFO    ][13864] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-27 02:53:56,469 [salt.state       :300 ][INFO    ][13864] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-27 02:53:56,469 [salt.state       :1951][INFO    ][13864] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:53:56.469777 duration_in_ms=0.78
2019-06-27 02:53:56,470 [salt.state       :1780][INFO    ][13864] Running state [/etc/salt/grains.d/dns_records] at time 02:53:56.470258
2019-06-27 02:53:56,470 [salt.state       :1813][INFO    ][13864] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-27 02:53:56,471 [salt.state       :300 ][INFO    ][13864] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-27 02:53:56,471 [salt.state       :1951][INFO    ][13864] Completed state [/etc/salt/grains.d/dns_records] at time 02:53:56.471591 duration_in_ms=1.333
2019-06-27 02:53:56,472 [salt.state       :1780][INFO    ][13864] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:56.472514
2019-06-27 02:53:56,472 [salt.state       :1813][INFO    ][13864] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-27 02:53:56,473 [salt.state       :300 ][INFO    ][13864] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-27 02:53:56,473 [salt.state       :1951][INFO    ][13864] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:53:56.473281 duration_in_ms=0.767
2019-06-27 02:53:56,476 [salt.state       :1780][INFO    ][13864] Running state [/etc/salt/grains.d/salt] at time 02:53:56.476306
2019-06-27 02:53:56,476 [salt.state       :1813][INFO    ][13864] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-27 02:53:56,477 [salt.state       :300 ][INFO    ][13864] File /etc/salt/grains.d/salt is in the correct state
2019-06-27 02:53:56,477 [salt.state       :1951][INFO    ][13864] Completed state [/etc/salt/grains.d/salt] at time 02:53:56.477671 duration_in_ms=1.365
2019-06-27 02:53:56,478 [salt.state       :1780][INFO    ][13864] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:56.478674
2019-06-27 02:53:56,478 [salt.state       :1813][INFO    ][13864] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-27 02:53:56,479 [salt.state       :300 ][INFO    ][13864] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-27 02:53:56,479 [salt.state       :1951][INFO    ][13864] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:53:56.479421 duration_in_ms=0.747
2019-06-27 02:53:56,481 [salt.state       :1780][INFO    ][13864] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:56.481338
2019-06-27 02:53:56,481 [salt.state       :1813][INFO    ][13864] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-27 02:53:56,481 [salt.state       :300 ][INFO    ][13864] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-27 02:53:56,482 [salt.state       :1951][INFO    ][13864] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:53:56.482124 duration_in_ms=0.786
2019-06-27 02:53:56,482 [salt.state       :1780][INFO    ][13864] Running state [mine.update] at time 02:53:56.482783
2019-06-27 02:53:56,483 [salt.state       :1813][INFO    ][13864] Executing state module.wait for [mine.update]
2019-06-27 02:53:56,483 [salt.state       :300 ][INFO    ][13864] No changes made for mine.update
2019-06-27 02:53:56,483 [salt.state       :1951][INFO    ][13864] Completed state [mine.update] at time 02:53:56.483480 duration_in_ms=0.697
2019-06-27 02:53:56,483 [salt.state       :1780][INFO    ][13864] Running state [ca-certificates] at time 02:53:56.483715
2019-06-27 02:53:56,483 [salt.state       :1813][INFO    ][13864] Executing state pkg.installed for [ca-certificates]
2019-06-27 02:53:56,494 [salt.state       :300 ][INFO    ][13864] All specified packages are already installed
2019-06-27 02:53:56,494 [salt.state       :1951][INFO    ][13864] Completed state [ca-certificates] at time 02:53:56.494554 duration_in_ms=10.839
2019-06-27 02:53:56,495 [salt.state       :1780][INFO    ][13864] Running state [update-ca-certificates] at time 02:53:56.495249
2019-06-27 02:53:56,495 [salt.state       :1813][INFO    ][13864] Executing state cmd.wait for [update-ca-certificates]
2019-06-27 02:53:56,495 [salt.state       :300 ][INFO    ][13864] No changes made for update-ca-certificates
2019-06-27 02:53:56,496 [salt.state       :1951][INFO    ][13864] Completed state [update-ca-certificates] at time 02:53:56.495993 duration_in_ms=0.744
2019-06-27 02:53:56,496 [salt.state       :1780][INFO    ][13864] Running state [iptables] at time 02:53:56.496231
2019-06-27 02:53:56,496 [salt.state       :1813][INFO    ][13864] Executing state pkg.installed for [iptables]
2019-06-27 02:53:56,506 [salt.state       :300 ][INFO    ][13864] All specified packages are already installed
2019-06-27 02:53:56,506 [salt.state       :1951][INFO    ][13864] Completed state [iptables] at time 02:53:56.506237 duration_in_ms=10.005
2019-06-27 02:53:56,506 [salt.state       :1780][INFO    ][13864] Running state [iptables-persistent] at time 02:53:56.506482
2019-06-27 02:53:56,506 [salt.state       :1813][INFO    ][13864] Executing state pkg.installed for [iptables-persistent]
2019-06-27 02:53:56,515 [salt.state       :300 ][INFO    ][13864] All specified packages are already installed
2019-06-27 02:53:56,515 [salt.state       :1951][INFO    ][13864] Completed state [iptables-persistent] at time 02:53:56.515721 duration_in_ms=9.239
2019-06-27 02:53:56,516 [salt.state       :1780][INFO    ][13864] Running state [iptables_modules_v4_load] at time 02:53:56.516682
2019-06-27 02:53:56,516 [salt.state       :1813][INFO    ][13864] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-27 02:53:56,517 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command 'lsmod' in directory '/root'
2019-06-27 02:53:56,536 [salt.state       :300 ][INFO    ][13864] Kernel modules iptable_filter, ip_tables are already present
2019-06-27 02:53:56,537 [salt.state       :1951][INFO    ][13864] Completed state [iptables_modules_v4_load] at time 02:53:56.537159 duration_in_ms=20.476
2019-06-27 02:53:56,538 [salt.state       :1780][INFO    ][13864] Running state [/etc/iptables/rules.v4] at time 02:53:56.538236
2019-06-27 02:53:56,538 [salt.state       :1813][INFO    ][13864] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-27 02:53:56,649 [salt.state       :300 ][INFO    ][13864] File /etc/iptables/rules.v4 is in the correct state
2019-06-27 02:53:56,649 [salt.state       :1951][INFO    ][13864] Completed state [/etc/iptables/rules.v4] at time 02:53:56.649859 duration_in_ms=111.623
2019-06-27 02:53:56,651 [salt.state       :1780][INFO    ][13864] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:56.650971
2019-06-27 02:53:56,651 [salt.state       :1813][INFO    ][13864] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-27 02:53:56,651 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-27 02:53:56,671 [salt.state       :300 ][INFO    ][13864] onlyif execution failed
2019-06-27 02:53:56,672 [salt.state       :1951][INFO    ][13864] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:53:56.671888 duration_in_ms=20.916
2019-06-27 02:53:56,674 [salt.state       :1780][INFO    ][13864] Running state [netfilter-persistent] at time 02:53:56.674394
2019-06-27 02:53:56,675 [salt.state       :1813][INFO    ][13864] Executing state service.running for [netfilter-persistent]
2019-06-27 02:53:56,676 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-27 02:53:56,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-27 02:53:56,725 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-27 02:53:56,747 [salt.state       :300 ][INFO    ][13864] The service netfilter-persistent is already running
2019-06-27 02:53:56,747 [salt.state       :1951][INFO    ][13864] Completed state [netfilter-persistent] at time 02:53:56.747696 duration_in_ms=73.302
2019-06-27 02:53:56,749 [salt.state       :1780][INFO    ][13864] Running state [iptables_extra.remove_stale_tables] at time 02:53:56.749187
2019-06-27 02:53:56,750 [salt.state       :1813][INFO    ][13864] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-27 02:53:56,751 [salt.state       :300 ][INFO    ][13864] No changes made for iptables_extra.remove_stale_tables
2019-06-27 02:53:56,751 [salt.state       :1951][INFO    ][13864] Completed state [iptables_extra.remove_stale_tables] at time 02:53:56.751791 duration_in_ms=2.603
2019-06-27 02:53:56,752 [salt.state       :1780][INFO    ][13864] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:56.752215
2019-06-27 02:53:56,752 [salt.state       :1813][INFO    ][13864] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-27 02:53:56,753 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13864] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-27 02:53:56,773 [salt.state       :300 ][INFO    ][13864] onlyif execution failed
2019-06-27 02:53:56,776 [salt.state       :1951][INFO    ][13864] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:53:56.776174 duration_in_ms=23.959
2019-06-27 02:53:56,779 [salt.state       :1780][INFO    ][13864] Running state [/etc/iptables/rules.v6] at time 02:53:56.779021
2019-06-27 02:53:56,779 [salt.state       :1813][INFO    ][13864] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-27 02:53:56,780 [salt.state       :300 ][INFO    ][13864] File /etc/iptables/rules.v6 is not present
2019-06-27 02:53:56,780 [salt.state       :1951][INFO    ][13864] Completed state [/etc/iptables/rules.v6] at time 02:53:56.780763 duration_in_ms=1.741
2019-06-27 02:53:56,782 [salt.state       :1780][INFO    ][13864] Running state [iptables_extra.flush_all] at time 02:53:56.782291
2019-06-27 02:53:56,782 [salt.state       :1813][INFO    ][13864] Executing state module.wait for [iptables_extra.flush_all]
2019-06-27 02:53:56,783 [salt.state       :300 ][INFO    ][13864] No changes made for iptables_extra.flush_all
2019-06-27 02:53:56,783 [salt.state       :1951][INFO    ][13864] Completed state [iptables_extra.flush_all] at time 02:53:56.783804 duration_in_ms=1.513
2019-06-27 02:53:56,792 [salt.minion      :1711][INFO    ][13864] Returning information for job: 20190627025343453127
2019-06-27 02:53:57,358 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627025357346748
2019-06-27 02:53:57,388 [salt.minion      :1432][INFO    ][13956] Starting a new job with PID 13956
2019-06-27 02:53:58,518 [salt.state       :915 ][INFO    ][13956] Loading fresh modules for state activity
2019-06-27 02:53:59,483 [salt.state       :1780][INFO    ][13956] Running state [maas-rack-controller] at time 02:53:59.483893
2019-06-27 02:53:59,484 [salt.state       :1813][INFO    ][13956] Executing state pkg.installed for [maas-rack-controller]
2019-06-27 02:53:59,485 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13956] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-27 02:53:59,612 [salt.state       :300 ][INFO    ][13956] All specified packages are already installed
2019-06-27 02:53:59,612 [salt.state       :1951][INFO    ][13956] Completed state [maas-rack-controller] at time 02:53:59.612686 duration_in_ms=128.795
2019-06-27 02:53:59,613 [salt.state       :1780][INFO    ][13956] Running state [ipmitool] at time 02:53:59.613008
2019-06-27 02:53:59,613 [salt.state       :1813][INFO    ][13956] Executing state pkg.installed for [ipmitool]
2019-06-27 02:53:59,622 [salt.state       :300 ][INFO    ][13956] All specified packages are already installed
2019-06-27 02:53:59,622 [salt.state       :1951][INFO    ][13956] Completed state [ipmitool] at time 02:53:59.622920 duration_in_ms=9.912
2019-06-27 02:53:59,630 [salt.state       :1780][INFO    ][13956] Running state [/etc/maas/rackd.conf] at time 02:53:59.630832
2019-06-27 02:53:59,631 [salt.state       :1813][INFO    ][13956] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-27 02:53:59,633 [salt.state       :300 ][INFO    ][13956] No changes needed to be made
2019-06-27 02:53:59,634 [salt.state       :1951][INFO    ][13956] Completed state [/etc/maas/rackd.conf] at time 02:53:59.634119 duration_in_ms=3.288
2019-06-27 02:53:59,634 [salt.state       :1780][INFO    ][13956] Running state [/etc/maas/rackd.conf] at time 02:53:59.634388
2019-06-27 02:53:59,634 [salt.state       :1813][INFO    ][13956] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-27 02:53:59,634 [salt.loaded.int.states.file:2298][WARNING ][13956] 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-06-27 02:53:59,635 [salt.state       :300 ][INFO    ][13956] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-27 02:53:59,636 [salt.state       :1951][INFO    ][13956] Completed state [/etc/maas/rackd.conf] at time 02:53:59.635914 duration_in_ms=1.526
2019-06-27 02:53:59,637 [salt.state       :1780][INFO    ][13956] Running state [maas-rackd] at time 02:53:59.636961
2019-06-27 02:53:59,637 [salt.state       :1813][INFO    ][13956] Executing state service.running for [maas-rackd]
2019-06-27 02:53:59,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13956] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-27 02:53:59,681 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13956] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-27 02:53:59,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13956] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-27 02:53:59,723 [salt.state       :300 ][INFO    ][13956] The service maas-rackd is already running
2019-06-27 02:53:59,724 [salt.state       :1951][INFO    ][13956] Completed state [maas-rackd] at time 02:53:59.724322 duration_in_ms=87.359
2019-06-27 02:53:59,728 [salt.minion      :1711][INFO    ][13956] Returning information for job: 20190627025357346748
2019-06-27 02:54:00,297 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627025400287016
2019-06-27 02:54:00,328 [salt.minion      :1432][INFO    ][13979] Starting a new job with PID 13979
2019-06-27 02:54:01,432 [salt.state       :915 ][INFO    ][13979] Loading fresh modules for state activity
2019-06-27 02:54:02,488 [salt.state       :1780][INFO    ][13979] Running state [maas-region-controller] at time 02:54:02.488442
2019-06-27 02:54:02,488 [salt.state       :1813][INFO    ][13979] Executing state pkg.installed for [maas-region-controller]
2019-06-27 02:54:02,489 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-27 02:54:02,603 [salt.state       :300 ][INFO    ][13979] All specified packages are already installed
2019-06-27 02:54:02,603 [salt.state       :1951][INFO    ][13979] Completed state [maas-region-controller] at time 02:54:02.603793 duration_in_ms=115.351
2019-06-27 02:54:02,604 [salt.state       :1780][INFO    ][13979] Running state [python-oauth] at time 02:54:02.604125
2019-06-27 02:54:02,604 [salt.state       :1813][INFO    ][13979] Executing state pkg.installed for [python-oauth]
2019-06-27 02:54:02,613 [salt.state       :300 ][INFO    ][13979] All specified packages are already installed
2019-06-27 02:54:02,613 [salt.state       :1951][INFO    ][13979] Completed state [python-oauth] at time 02:54:02.613685 duration_in_ms=9.56
2019-06-27 02:54:02,616 [salt.state       :1780][INFO    ][13979] Running state [/etc/maas/regiond.conf] at time 02:54:02.616890
2019-06-27 02:54:02,617 [salt.state       :1813][INFO    ][13979] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-27 02:54:02,621 [salt.state       :300 ][INFO    ][13979] No changes needed to be made
2019-06-27 02:54:02,622 [salt.state       :1951][INFO    ][13979] Completed state [/etc/maas/regiond.conf] at time 02:54:02.622043 duration_in_ms=5.153
2019-06-27 02:54:02,622 [salt.state       :1780][INFO    ][13979] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:54:02.622518
2019-06-27 02:54:02,622 [salt.state       :1813][INFO    ][13979] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-27 02:54:02,692 [salt.state       :300 ][INFO    ][13979] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-27 02:54:02,692 [salt.state       :1951][INFO    ][13979] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:54:02.692578 duration_in_ms=70.059
2019-06-27 02:54:02,693 [salt.state       :1780][INFO    ][13979] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:02.693074
2019-06-27 02:54:02,693 [salt.state       :1813][INFO    ][13979] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-27 02:54:02,697 [salt.state       :300 ][INFO    ][13979] No changes needed to be made
2019-06-27 02:54:02,697 [salt.state       :1951][INFO    ][13979] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:02.697352 duration_in_ms=4.278
2019-06-27 02:54:02,698 [salt.state       :1780][INFO    ][13979] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:02.698477
2019-06-27 02:54:02,698 [salt.state       :1813][INFO    ][13979] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-27 02:54:02,703 [salt.state       :300 ][INFO    ][13979] No changes needed to be made
2019-06-27 02:54:02,703 [salt.state       :1951][INFO    ][13979] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:02.703837 duration_in_ms=5.359
2019-06-27 02:54:02,704 [salt.state       :1780][INFO    ][13979] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:54:02.704331
2019-06-27 02:54:02,704 [salt.state       :1813][INFO    ][13979] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-27 02:54:02,735 [salt.state       :300 ][INFO    ][13979] No changes needed to be made
2019-06-27 02:54:02,735 [salt.state       :1951][INFO    ][13979] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:54:02.735326 duration_in_ms=30.995
2019-06-27 02:54:02,735 [salt.state       :1780][INFO    ][13979] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:54:02.735864
2019-06-27 02:54:02,736 [salt.state       :1813][INFO    ][13979] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-27 02:54:02,749 [salt.state       :300 ][INFO    ][13979] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-27 02:54:02,749 [salt.state       :1951][INFO    ][13979] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:54:02.749575 duration_in_ms=13.712
2019-06-27 02:54:02,752 [salt.state       :1780][INFO    ][13979] Running state [a2enmod headers] at time 02:54:02.752787
2019-06-27 02:54:02,753 [salt.state       :1813][INFO    ][13979] Executing state cmd.run for [a2enmod headers]
2019-06-27 02:54:02,753 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command 'a2enmod headers' in directory '/root'
2019-06-27 02:54:02,824 [salt.state       :300 ][INFO    ][13979] {'pid': 13998, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-27 02:54:02,825 [salt.state       :1951][INFO    ][13979] Completed state [a2enmod headers] at time 02:54:02.825441 duration_in_ms=72.653
2019-06-27 02:54:02,826 [salt.state       :1780][INFO    ][13979] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:54:02.826781
2019-06-27 02:54:02,827 [salt.state       :1813][INFO    ][13979] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-27 02:54:02,857 [salt.state       :300 ][INFO    ][13979] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-27 02:54:02,860 [salt.state       :1951][INFO    ][13979] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:54:02.860395 duration_in_ms=33.616
2019-06-27 02:54:02,861 [salt.state       :1780][INFO    ][13979] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:54:02.860974
2019-06-27 02:54:02,861 [salt.state       :1813][INFO    ][13979] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-27 02:54:02,922 [salt.state       :300 ][INFO    ][13979] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-27 02:54:02,922 [salt.state       :1951][INFO    ][13979] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:54:02.922394 duration_in_ms=61.42
2019-06-27 02:54:02,922 [salt.state       :1780][INFO    ][13979] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:54:02.922913
2019-06-27 02:54:02,923 [salt.state       :1813][INFO    ][13979] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-27 02:54:02,991 [salt.state       :300 ][INFO    ][13979] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-27 02:54:02,991 [salt.state       :1951][INFO    ][13979] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:54:02.991382 duration_in_ms=68.469
2019-06-27 02:54:02,991 [salt.state       :1780][INFO    ][13979] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:54:02.991919
2019-06-27 02:54:02,992 [salt.state       :1813][INFO    ][13979] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-27 02:54:03,078 [salt.state       :300 ][INFO    ][13979] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-27 02:54:03,078 [salt.state       :1951][INFO    ][13979] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:54:03.078310 duration_in_ms=86.391
2019-06-27 02:54:03,078 [salt.state       :1780][INFO    ][13979] Running state [/root/.pgpass] at time 02:54:03.078831
2019-06-27 02:54:03,079 [salt.state       :1813][INFO    ][13979] Executing state file.managed for [/root/.pgpass]
2019-06-27 02:54:03,132 [salt.state       :300 ][INFO    ][13979] File /root/.pgpass is in the correct state
2019-06-27 02:54:03,133 [salt.state       :1951][INFO    ][13979] Completed state [/root/.pgpass] at time 02:54:03.133086 duration_in_ms=54.255
2019-06-27 02:54:03,139 [salt.state       :1780][INFO    ][13979] Running state [maas-region syncdb --noinput] at time 02:54:03.139178
2019-06-27 02:54:03,139 [salt.state       :1813][INFO    ][13979] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-27 02:54:03,140 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-27 02:54:05,704 [salt.state       :300 ][INFO    ][13979] {'pid': 14011, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, piston3, maasserver, sessions, sites, contenttypes, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-27 02:54:05,705 [salt.state       :1951][INFO    ][13979] Completed state [maas-region syncdb --noinput] at time 02:54:05.705362 duration_in_ms=2566.182
2019-06-27 02:54:05,706 [salt.state       :2022][WARNING ][13979] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-27 02:54:05,709 [salt.state       :1780][INFO    ][13979] Running state [maas-regiond] at time 02:54:05.709697
2019-06-27 02:54:05,710 [salt.state       :1813][INFO    ][13979] Executing state service.running for [maas-regiond]
2019-06-27 02:54:05,711 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-27 02:54:05,753 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-27 02:54:05,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-27 02:54:05,788 [salt.state       :300 ][INFO    ][13979] The service maas-regiond is already running
2019-06-27 02:54:05,789 [salt.state       :1951][INFO    ][13979] Completed state [maas-regiond] at time 02:54:05.789233 duration_in_ms=79.537
2019-06-27 02:54:05,792 [salt.state       :1780][INFO    ][13979] Running state [bind9] at time 02:54:05.792126
2019-06-27 02:54:05,792 [salt.state       :1813][INFO    ][13979] Executing state service.running for [bind9]
2019-06-27 02:54:05,793 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-27 02:54:05,814 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-27 02:54:05,833 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-27 02:54:05,852 [salt.state       :300 ][INFO    ][13979] The service bind9 is already running
2019-06-27 02:54:05,852 [salt.state       :1951][INFO    ][13979] Completed state [bind9] at time 02:54:05.852809 duration_in_ms=60.684
2019-06-27 02:54:05,855 [salt.state       :1780][INFO    ][13979] Running state [apache2] at time 02:54:05.855520
2019-06-27 02:54:05,855 [salt.state       :1813][INFO    ][13979] Executing state service.running for [apache2]
2019-06-27 02:54:05,856 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-27 02:54:05,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-27 02:54:05,901 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-27 02:54:05,934 [salt.state       :300 ][INFO    ][13979] The service apache2 is already running
2019-06-27 02:54:05,934 [salt.state       :1951][INFO    ][13979] Completed state [apache2] at time 02:54:05.934828 duration_in_ms=79.307
2019-06-27 02:54:05,936 [salt.state       :1780][INFO    ][13979] Running state [maasng.wait_for_http_code] at time 02:54:05.936663
2019-06-27 02:54:05,937 [salt.state       :1813][INFO    ][13979] Executing state module.run for [maasng.wait_for_http_code]
2019-06-27 02:54:05,938 [salt.utils.decorators:613 ][WARNING ][13979] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:54:05,948 [salt.state       :300 ][INFO    ][13979] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-27 02:54:05,949 [salt.state       :1951][INFO    ][13979] Completed state [maasng.wait_for_http_code] at time 02:54:05.948992 duration_in_ms=12.33
2019-06-27 02:54:05,949 [salt.state       :1780][INFO    ][13979] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:54:05.949767
2019-06-27 02:54:05,950 [salt.state       :1813][INFO    ][13979] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-27 02:54:05,950 [salt.state       :300 ][INFO    ][13979] /var/lib/maas/.setup_admin exists
2019-06-27 02:54:05,950 [salt.state       :1951][INFO    ][13979] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:54:05.950654 duration_in_ms=0.887
2019-06-27 02:54:05,951 [salt.state       :1780][INFO    ][13979] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:05.951343
2019-06-27 02:54:05,951 [salt.state       :1813][INFO    ][13979] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-27 02:54:05,952 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13979] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-27 02:54:07,670 [salt.state       :300 ][INFO    ][13979] {'pid': 14030, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-27 02:54:07,671 [salt.state       :1951][INFO    ][13979] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:07.671664 duration_in_ms=1720.319
2019-06-27 02:54:07,683 [salt.state       :1780][INFO    ][13979] Running state [maas_region_boot_source_resources_mirror] at time 02:54:07.683203
2019-06-27 02:54:07,683 [salt.state       :1813][INFO    ][13979] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-27 02:54:07,770 [salt.state       :300 ][INFO    ][13979] {'changes': {}}
2019-06-27 02:54:07,771 [salt.state       :1951][INFO    ][13979] Completed state [maas_region_boot_source_resources_mirror] at time 02:54:07.770951 duration_in_ms=87.747
2019-06-27 02:54:07,772 [salt.state       :1780][INFO    ][13979] Running state [maasng.boot_resources_import] at time 02:54:07.772105
2019-06-27 02:54:07,772 [salt.state       :1813][INFO    ][13979] Executing state module.run for [maasng.boot_resources_import]
2019-06-27 02:54:07,773 [salt.utils.decorators:613 ][WARNING ][13979] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:54:07,864 [salt.loaded.ext.module.maasng:1600][INFO    ][13979] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-27 02:54:12,906 [salt.loaded.ext.module.maasng:1600][INFO    ][13979] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-27 02:54:15,381 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025415367133
2019-06-27 02:54:15,419 [salt.minion      :1432][INFO    ][14084] Starting a new job with PID 14084
2019-06-27 02:54:15,449 [salt.minion      :1711][INFO    ][14084] Returning information for job: 20190627025415367133
2019-06-27 02:54:18,015 [salt.state       :300 ][INFO    ][13979] {'ret': True}
2019-06-27 02:54:18,016 [salt.state       :1951][INFO    ][13979] Completed state [maasng.boot_resources_import] at time 02:54:18.016380 duration_in_ms=10244.273
2019-06-27 02:54:18,018 [salt.state       :1780][INFO    ][13979] Running state [maas_region_boot_sources_selection_xenial] at time 02:54:18.018696
2019-06-27 02:54:18,019 [salt.state       :1813][INFO    ][13979] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-27 02:54:18,220 [salt.state       :300 ][INFO    ][13979] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-27 02:54:18,220 [salt.state       :1951][INFO    ][13979] Completed state [maas_region_boot_sources_selection_xenial] at time 02:54:18.220767 duration_in_ms=202.071
2019-06-27 02:54:18,222 [salt.state       :1780][INFO    ][13979] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:54:18.222707
2019-06-27 02:54:18,223 [salt.state       :1813][INFO    ][13979] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-27 02:54:18,223 [salt.utils.decorators:613 ][WARNING ][13979] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:54:18,224 [salt.loaded.ext.module.maasng:1771][INFO    ][13979] boot-sources sync initiated for ALL Rack's
2019-06-27 02:54:19,295 [salt.state       :300 ][INFO    ][13979] {'ret': True}
2019-06-27 02:54:19,296 [salt.state       :1951][INFO    ][13979] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:54:19.296376 duration_in_ms=1073.667
2019-06-27 02:54:19,299 [salt.state       :1780][INFO    ][13979] Running state [maas.process_maas_config] at time 02:54:19.299466
2019-06-27 02:54:19,299 [salt.state       :1813][INFO    ][13979] Executing state module.run for [maas.process_maas_config]
2019-06-27 02:54:19,300 [salt.utils.decorators:613 ][WARNING ][13979] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:54:19,302 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=enable_http_proxy value=True
2019-06-27 02:54:19,352 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=upstream_dns value=8.8.8.8
2019-06-27 02:54:19,408 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=commissioning_distro_series value=xenial
2019-06-27 02:54:19,464 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=default_osystem value=ubuntu
2019-06-27 02:54:19,521 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=active_discovery_interval value=600
2019-06-27 02:54:20,984 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=dnssec_validation value=no
2019-06-27 02:54:21,043 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=maas_name value=mas01
2019-06-27 02:54:21,101 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=network_discovery value=enabled
2019-06-27 02:54:21,202 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=enable_third_party_drivers value=True
2019-06-27 02:54:21,256 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=default_storage_layout value=lvm
2019-06-27 02:54:21,319 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=ntp_external_only value=True
2019-06-27 02:54:21,370 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-27 02:54:21,418 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=default_distro_series value=xenial
2019-06-27 02:54:21,476 [salt.loaded.ext.module.maas:92  ][INFO    ][13979] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-06-27 02:54:21,592 [salt.state       :300 ][INFO    ][13979] {'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-06-27 02:54:21,593 [salt.state       :1951][INFO    ][13979] Completed state [maas.process_maas_config] at time 02:54:21.593135 duration_in_ms=2293.669
2019-06-27 02:54:21,595 [salt.state       :1780][INFO    ][13979] Running state [pxe_admin] at time 02:54:21.595180
2019-06-27 02:54:21,595 [salt.state       :1813][INFO    ][13979] Executing state maasng.fabric_present for [pxe_admin]
2019-06-27 02:54:21,670 [salt.loaded.ext.module.maasng:945 ][INFO    ][13979] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'q4hcra', 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'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-27 02:54:21,734 [salt.loaded.ext.module.maasng:1008][WARNING ][13979] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-27 02:54:21,734 [salt.loaded.ext.module.maasng:1011][WARNING ][13979] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-27 02:54:21,797 [salt.state       :300 ][INFO    ][13979] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-27 02:54:21,797 [salt.state       :1951][INFO    ][13979] Completed state [pxe_admin] at time 02:54:21.797539 duration_in_ms=202.357
2019-06-27 02:54:21,798 [salt.state       :1780][INFO    ][13979] Running state [vlan 0] at time 02:54:21.798905
2019-06-27 02:54:21,799 [salt.state       :1813][INFO    ][13979] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-27 02:54:21,853 [salt.loaded.ext.module.maasng:945 ][INFO    ][13979] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'q4hcra', 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'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-27 02:54:21,958 [salt.loaded.ext.module.maasng:945 ][INFO    ][13979] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'q4hcra', 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'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-27 02:54:22,219 [salt.loaded.ext.module.maasng:945 ][INFO    ][13979] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-06-27 02:54:22,303 [salt.state       :300 ][INFO    ][13979] {'new': 'Vlan untagged was updated'}
2019-06-27 02:54:22,304 [salt.state       :1951][INFO    ][13979] Completed state [vlan 0] at time 02:54:22.303962 duration_in_ms=505.056
2019-06-27 02:54:22,305 [salt.state       :1780][INFO    ][13979] Running state [192.168.11.0/24] at time 02:54:22.304992
2019-06-27 02:54:22,305 [salt.state       :1813][INFO    ][13979] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-27 02:54:22,484 [salt.loaded.ext.module.maasng:945 ][INFO    ][13979] [{u'name': u'fabric-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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, 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/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', 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': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-06-27 02:54:22,485 [salt.loaded.ext.module.maasng:1235][WARNING ][13979] Ignoring parameter vlan:0
2019-06-27 02:54:22,556 [salt.state       :300 ][INFO    ][13979] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-27 02:54:22,556 [salt.state       :1951][INFO    ][13979] Completed state [192.168.11.0/24] at time 02:54:22.556244 duration_in_ms=251.251
2019-06-27 02:54:22,557 [salt.state       :1780][INFO    ][13979] Running state [maas_create_iprange_1] at time 02:54:22.557108
2019-06-27 02:54:22,557 [salt.state       :1813][INFO    ][13979] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-27 02:54:22,608 [salt.state       :300 ][INFO    ][13979] Iprange maas_create_iprange_1 already exist.
2019-06-27 02:54:22,608 [salt.state       :1951][INFO    ][13979] Completed state [maas_create_iprange_1] at time 02:54:22.608761 duration_in_ms=51.653
2019-06-27 02:54:22,609 [salt.state       :1780][INFO    ][13979] Running state [vlan 0] at time 02:54:22.609053
2019-06-27 02:54:22,609 [salt.state       :1813][INFO    ][13979] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-27 02:54:22,659 [salt.loaded.ext.module.maasng:945 ][INFO    ][13979] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'q4hcra', 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'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-27 02:54:22,776 [salt.loaded.ext.module.maasng:945 ][INFO    ][13979] [{u'id': 0, u'class_type': None, 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'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'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'q4hcra', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-27 02:54:23,025 [salt.loaded.ext.module.maasng:945 ][INFO    ][13979] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'q4hcra', 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'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-27 02:54:23,131 [salt.state       :300 ][INFO    ][13979] {'new': 'Vlan untagged was updated'}
2019-06-27 02:54:23,132 [salt.state       :1951][INFO    ][13979] Completed state [vlan 0] at time 02:54:23.132210 duration_in_ms=523.156
2019-06-27 02:54:23,132 [salt.state       :1780][INFO    ][13979] Running state [opnfv] at time 02:54:23.132935
2019-06-27 02:54:23,133 [salt.state       :1813][INFO    ][13979] Executing state maasng.sshkey_present for [opnfv]
2019-06-27 02:54:23,184 [salt.loaded.ext.module.maasng:1903][INFO    ][13979] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-06-27 02:54:23,185 [salt.state       :300 ][INFO    ][13979] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-27 02:54:23,185 [salt.state       :1951][INFO    ][13979] Completed state [opnfv] at time 02:54:23.185265 duration_in_ms=52.33
2019-06-27 02:54:23,190 [salt.minion      :1711][INFO    ][13979] Returning information for job: 20190627025400287016
2019-06-27 02:54:23,796 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627025423780943
2019-06-27 02:54:23,831 [salt.minion      :1432][INFO    ][14438] Starting a new job with PID 14438
2019-06-27 02:54:32,285 [salt.state       :915 ][INFO    ][14438] Loading fresh modules for state activity
2019-06-27 02:54:32,394 [salt.state       :1780][INFO    ][14438] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:32.394483
2019-06-27 02:54:32,394 [salt.state       :1813][INFO    ][14438] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-27 02:54:32,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14438] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-27 02:54:34,111 [salt.state       :300 ][INFO    ][14438] {'pid': 14468, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-27 02:54:34,112 [salt.state       :1951][INFO    ][14438] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:34.111865 duration_in_ms=1717.382
2019-06-27 02:54:34,114 [salt.state       :1780][INFO    ][14438] Running state [maas.process_machines] at time 02:54:34.114633
2019-06-27 02:54:34,115 [salt.state       :1813][INFO    ][14438] Executing state module.run for [maas.process_machines]
2019-06-27 02:54:34,116 [salt.utils.decorators:613 ][WARNING ][14438] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:54:34,705 [salt.loaded.ext.module.maas:412 ][WARNING ][14438] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-27 02:54:34,707 [salt.loaded.ext.module.maas:92  ][INFO    ][14438] machine hostname=cmp002 power_type=ipmi mac_addresses=['9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=gecfx7 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-27 02:54:35,921 [salt.loaded.ext.module.maas:412 ][WARNING ][14438] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-27 02:54:35,923 [salt.loaded.ext.module.maas:92  ][INFO    ][14438] machine hostname=cmp001 power_type=ipmi mac_addresses=['9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=qndbm6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-27 02:54:37,163 [salt.loaded.ext.module.maas:412 ][WARNING ][14438] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-27 02:54:37,163 [salt.loaded.ext.module.maas:92  ][INFO    ][14438] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=gdh7eh architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-27 02:54:38,396 [salt.loaded.ext.module.maas:412 ][WARNING ][14438] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-27 02:54:38,397 [salt.loaded.ext.module.maas:92  ][INFO    ][14438] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=4dftg3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-27 02:54:38,828 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025438809732
2019-06-27 02:54:38,861 [salt.minion      :1432][INFO    ][14632] Starting a new job with PID 14632
2019-06-27 02:54:38,893 [salt.minion      :1711][INFO    ][14632] Returning information for job: 20190627025438809732
2019-06-27 02:54:39,632 [salt.loaded.ext.module.maas:412 ][WARNING ][14438] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-27 02:54:39,633 [salt.loaded.ext.module.maas:92  ][INFO    ][14438] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=xmcwna architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-27 02:54:40,806 [salt.state       :300 ][INFO    ][14438] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-27 02:54:40,807 [salt.state       :1951][INFO    ][14438] Completed state [maas.process_machines] at time 02:54:40.807089 duration_in_ms=6692.455
2019-06-27 02:54:40,810 [salt.minion      :1711][INFO    ][14438] Returning information for job: 20190627025423780943
2019-06-27 02:55:13,793 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627025513778789
2019-06-27 02:55:13,822 [salt.minion      :1432][INFO    ][14715] Starting a new job with PID 14715
2019-06-27 02:55:22,123 [salt.state       :915 ][INFO    ][14715] Loading fresh modules for state activity
2019-06-27 02:55:22,238 [salt.state       :1780][INFO    ][14715] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:22.238853
2019-06-27 02:55:22,239 [salt.state       :1813][INFO    ][14715] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-27 02:55:22,240 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14715] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-27 02:55:23,973 [salt.state       :300 ][INFO    ][14715] {'pid': 14752, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-27 02:55:23,974 [salt.state       :1951][INFO    ][14715] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:23.974462 duration_in_ms=1735.609
2019-06-27 02:55:23,979 [salt.state       :1780][INFO    ][14715] Running state [maas.wait_for_machine_status] at time 02:55:23.978987
2019-06-27 02:55:23,979 [salt.state       :1813][INFO    ][14715] Executing state module.run for [maas.wait_for_machine_status]
2019-06-27 02:55:23,980 [salt.utils.decorators:613 ][WARNING ][14715] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:55:26,790 [salt.state       :300 ][INFO    ][14715] {'ret': True}
2019-06-27 02:55:26,791 [salt.state       :1951][INFO    ][14715] Completed state [maas.wait_for_machine_status] at time 02:55:26.791219 duration_in_ms=2812.23
2019-06-27 02:55:26,795 [salt.minion      :1711][INFO    ][14715] Returning information for job: 20190627025513778789
2019-06-27 02:55:27,394 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627025527340803
2019-06-27 02:55:27,424 [salt.minion      :1432][INFO    ][14774] Starting a new job with PID 14774
2019-06-27 02:55:28,584 [salt.state       :915 ][INFO    ][14774] Loading fresh modules for state activity
2019-06-27 02:55:28,739 [salt.state       :1780][INFO    ][14774] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:28.739255
2019-06-27 02:55:28,739 [salt.state       :1813][INFO    ][14774] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-27 02:55:28,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14774] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-27 02:55:30,478 [salt.state       :300 ][INFO    ][14774] {'pid': 14781, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-27 02:55:30,479 [salt.state       :1951][INFO    ][14774] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:30.479142 duration_in_ms=1739.887
2019-06-27 02:55:30,481 [salt.state       :1780][INFO    ][14774] Running state [maas_machines_storage_cmp002_lvm] at time 02:55:30.481108
2019-06-27 02:55:30,481 [salt.state       :1813][INFO    ][14774] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-27 02:55:31,060 [salt.state       :300 ][INFO    ][14774] Machine cmp002 is not in Ready state.
2019-06-27 02:55:31,060 [salt.state       :1951][INFO    ][14774] Completed state [maas_machines_storage_cmp002_lvm] at time 02:55:31.060446 duration_in_ms=579.338
2019-06-27 02:55:31,060 [salt.state       :1780][INFO    ][14774] Running state [maas_machines_storage_cmp001_lvm] at time 02:55:31.060778
2019-06-27 02:55:31,061 [salt.state       :1813][INFO    ][14774] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-27 02:55:31,592 [salt.state       :300 ][INFO    ][14774] Machine cmp001 is not in Ready state.
2019-06-27 02:55:31,593 [salt.state       :1951][INFO    ][14774] Completed state [maas_machines_storage_cmp001_lvm] at time 02:55:31.593272 duration_in_ms=532.493
2019-06-27 02:55:31,597 [salt.minion      :1711][INFO    ][14774] Returning information for job: 20190627025527340803
2019-06-27 02:55:32,160 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627025532147164
2019-06-27 02:55:32,188 [salt.minion      :1432][INFO    ][14791] Starting a new job with PID 14791
2019-06-27 02:55:33,326 [salt.state       :915 ][INFO    ][14791] Loading fresh modules for state activity
2019-06-27 02:55:33,434 [salt.state       :1780][INFO    ][14791] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:33.434331
2019-06-27 02:55:33,434 [salt.state       :1813][INFO    ][14791] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-27 02:55:33,436 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14791] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-27 02:55:35,172 [salt.state       :300 ][INFO    ][14791] {'pid': 14798, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-27 02:55:35,173 [salt.state       :1951][INFO    ][14791] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:35.173399 duration_in_ms=1739.069
2019-06-27 02:55:35,178 [salt.state       :1780][INFO    ][14791] Running state [maas.deploy_machines] at time 02:55:35.177643
2019-06-27 02:55:35,178 [salt.state       :1813][INFO    ][14791] Executing state module.run for [maas.deploy_machines]
2019-06-27 02:55:35,179 [salt.utils.decorators:613 ][WARNING ][14791] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:55:35,764 [salt.loaded.ext.module.maas:684 ][INFO    ][14791] deploymachines hwe_kernel=ga-16.04 system_id=xmcwna distro_series=xenial
2019-06-27 02:55:38,233 [salt.state       :300 ][INFO    ][14791] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-27 02:55:38,236 [salt.state       :1951][INFO    ][14791] Completed state [maas.deploy_machines] at time 02:55:38.236710 duration_in_ms=3059.067
2019-06-27 02:55:38,240 [salt.minion      :1711][INFO    ][14791] Returning information for job: 20190627025532147164
2019-06-27 02:55:38,803 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command state.apply with jid 20190627025538789227
2019-06-27 02:55:38,832 [salt.minion      :1432][INFO    ][14858] Starting a new job with PID 14858
2019-06-27 02:55:46,987 [salt.state       :915 ][INFO    ][14858] Loading fresh modules for state activity
2019-06-27 02:55:47,095 [salt.state       :1780][INFO    ][14858] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:47.095488
2019-06-27 02:55:47,095 [salt.state       :1813][INFO    ][14858] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-27 02:55:47,097 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14858] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-27 02:55:48,816 [salt.state       :300 ][INFO    ][14858] {'pid': 14870, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-27 02:55:48,817 [salt.state       :1951][INFO    ][14858] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:48.817013 duration_in_ms=1721.525
2019-06-27 02:55:48,821 [salt.state       :1780][INFO    ][14858] Running state [maas.wait_for_machine_status] at time 02:55:48.821013
2019-06-27 02:55:48,821 [salt.state       :1813][INFO    ][14858] Executing state module.run for [maas.wait_for_machine_status]
2019-06-27 02:55:48,822 [salt.utils.decorators:613 ][WARNING ][14858] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-27 02:55:51,507 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.32696009s left)
2019-06-27 02:55:53,849 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025553829437
2019-06-27 02:55:53,880 [salt.minion      :1432][INFO    ][14881] Starting a new job with PID 14881
2019-06-27 02:55:53,902 [salt.minion      :1711][INFO    ][14881] Returning information for job: 20190627025553829437
2019-06-27 02:56:23,927 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025623912691
2019-06-27 02:56:23,949 [salt.minion      :1432][INFO    ][14937] Starting a new job with PID 14937
2019-06-27 02:56:23,974 [salt.minion      :1711][INFO    ][14937] Returning information for job: 20190627025623912691
2019-06-27 02:56:24,611 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.22281313s left)
2019-06-27 02:56:54,029 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025654011683
2019-06-27 02:56:54,063 [salt.minion      :1432][INFO    ][14954] Starting a new job with PID 14954
2019-06-27 02:56:54,087 [salt.minion      :1711][INFO    ][14954] Returning information for job: 20190627025654011683
2019-06-27 02:56:57,663 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.170784s left)
2019-06-27 02:57:24,115 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025724105872
2019-06-27 02:57:24,139 [salt.minion      :1432][INFO    ][15031] Starting a new job with PID 15031
2019-06-27 02:57:24,163 [salt.minion      :1711][INFO    ][15031] Returning information for job: 20190627025724105872
2019-06-27 02:57:30,464 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.37012911s left)
2019-06-27 02:57:54,193 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025754182277
2019-06-27 02:57:54,223 [salt.minion      :1432][INFO    ][15055] Starting a new job with PID 15055
2019-06-27 02:57:54,251 [salt.minion      :1711][INFO    ][15055] Returning information for job: 20190627025754182277
2019-06-27 02:58:03,196 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.63834596s left)
2019-06-27 02:58:24,279 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025824262308
2019-06-27 02:58:24,309 [salt.minion      :1432][INFO    ][15118] Starting a new job with PID 15118
2019-06-27 02:58:24,332 [salt.minion      :1711][INFO    ][15118] Returning information for job: 20190627025824262308
2019-06-27 02:58:35,951 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.88287091s left)
2019-06-27 02:58:54,368 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025854355270
2019-06-27 02:58:54,400 [salt.minion      :1432][INFO    ][15142] Starting a new job with PID 15142
2019-06-27 02:58:54,424 [salt.minion      :1711][INFO    ][15142] Returning information for job: 20190627025854355270
2019-06-27 02:59:08,707 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.1270709s left)
2019-06-27 02:59:24,473 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025924452986
2019-06-27 02:59:24,504 [salt.minion      :1432][INFO    ][15200] Starting a new job with PID 15200
2019-06-27 02:59:24,533 [salt.minion      :1711][INFO    ][15200] Returning information for job: 20190627025924452986
2019-06-27 02:59:41,645 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.18908405s left)
2019-06-27 02:59:54,552 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627025954543249
2019-06-27 02:59:54,573 [salt.minion      :1432][INFO    ][15246] Starting a new job with PID 15246
2019-06-27 02:59:54,593 [salt.minion      :1711][INFO    ][15246] Returning information for job: 20190627025954543249
2019-06-27 03:00:14,885 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1983.94946694s left)
2019-06-27 03:00:24,637 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030024619723
2019-06-27 03:00:24,666 [salt.minion      :1432][INFO    ][15326] Starting a new job with PID 15326
2019-06-27 03:00:24,691 [salt.minion      :1711][INFO    ][15326] Returning information for job: 20190627030024619723
2019-06-27 03:00:47,568 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.26648808s left)
2019-06-27 03:00:54,742 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030054726393
2019-06-27 03:00:54,772 [salt.minion      :1432][INFO    ][15352] Starting a new job with PID 15352
2019-06-27 03:00:54,796 [salt.minion      :1711][INFO    ][15352] Returning information for job: 20190627030054726393
2019-06-27 03:01:20,429 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.40567398s left)
2019-06-27 03:01:24,872 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030124862960
2019-06-27 03:01:24,898 [salt.minion      :1432][INFO    ][15570] Starting a new job with PID 15570
2019-06-27 03:01:24,922 [salt.minion      :1711][INFO    ][15570] Returning information for job: 20190627030124862960
2019-06-27 03:01:53,243 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.59114194s left)
2019-06-27 03:01:54,979 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030154966968
2019-06-27 03:01:55,012 [salt.minion      :1432][INFO    ][15593] Starting a new job with PID 15593
2019-06-27 03:01:55,036 [salt.minion      :1711][INFO    ][15593] Returning information for job: 20190627030154966968
2019-06-27 03:02:25,135 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030225122017
2019-06-27 03:02:25,162 [salt.minion      :1432][INFO    ][15728] Starting a new job with PID 15728
2019-06-27 03:02:25,185 [salt.minion      :1711][INFO    ][15728] Returning information for job: 20190627030225122017
2019-06-27 03:02:26,266 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.56903911s left)
2019-06-27 03:02:55,264 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030255247832
2019-06-27 03:02:55,289 [salt.minion      :1432][INFO    ][15747] Starting a new job with PID 15747
2019-06-27 03:02:55,312 [salt.minion      :1711][INFO    ][15747] Returning information for job: 20190627030255247832
2019-06-27 03:02:59,290 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.54423213s left)
2019-06-27 03:03:25,424 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030325406683
2019-06-27 03:03:25,456 [salt.minion      :1432][INFO    ][15880] Starting a new job with PID 15880
2019-06-27 03:03:25,482 [salt.minion      :1711][INFO    ][15880] Returning information for job: 20190627030325406683
2019-06-27 03:03:32,082 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.752352s left)
2019-06-27 03:03:55,564 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030355552282
2019-06-27 03:03:55,583 [salt.minion      :1432][INFO    ][15900] Starting a new job with PID 15900
2019-06-27 03:03:55,612 [salt.minion      :1711][INFO    ][15900] Returning information for job: 20190627030355552282
2019-06-27 03:04:04,823 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.01166511s left)
2019-06-27 03:04:25,654 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030425641579
2019-06-27 03:04:25,680 [salt.minion      :1432][INFO    ][16005] Starting a new job with PID 16005
2019-06-27 03:04:25,705 [salt.minion      :1711][INFO    ][16005] Returning information for job: 20190627030425641579
2019-06-27 03:04:37,899 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.93495393s left)
2019-06-27 03:04:55,805 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030455796631
2019-06-27 03:04:55,834 [salt.minion      :1432][INFO    ][16029] Starting a new job with PID 16029
2019-06-27 03:04:55,856 [salt.minion      :1711][INFO    ][16029] Returning information for job: 20190627030455796631
2019-06-27 03:05:10,412 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1688.42215109s left)
2019-06-27 03:05:25,890 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030525879025
2019-06-27 03:05:25,918 [salt.minion      :1432][INFO    ][16083] Starting a new job with PID 16083
2019-06-27 03:05:25,940 [salt.minion      :1711][INFO    ][16083] Returning information for job: 20190627030525879025
2019-06-27 03:05:43,126 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1655.70860291s left)
2019-06-27 03:05:55,936 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030555924570
2019-06-27 03:05:55,962 [salt.minion      :1432][INFO    ][16105] Starting a new job with PID 16105
2019-06-27 03:05:55,988 [salt.minion      :1711][INFO    ][16105] Returning information for job: 20190627030555924570
2019-06-27 03:06:15,805 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1623.02964711s left)
2019-06-27 03:06:26,051 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030626042828
2019-06-27 03:06:26,074 [salt.minion      :1432][INFO    ][16166] Starting a new job with PID 16166
2019-06-27 03:06:26,108 [salt.minion      :1711][INFO    ][16166] Returning information for job: 20190627030626042828
2019-06-27 03:06:48,455 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1590.37965894s left)
2019-06-27 03:06:56,233 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030656223633
2019-06-27 03:06:56,254 [salt.minion      :1432][INFO    ][16201] Starting a new job with PID 16201
2019-06-27 03:06:56,278 [salt.minion      :1711][INFO    ][16201] Returning information for job: 20190627030656223633
2019-06-27 03:07:21,344 [salt.loaded.ext.module.maas:1023][INFO    ][14858] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1557.49021697s left)
2019-06-27 03:07:26,388 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command saltutil.find_job with jid 20190627030726380700
2019-06-27 03:07:26,400 [salt.minion      :1432][INFO    ][16419] Starting a new job with PID 16419
2019-06-27 03:07:26,422 [salt.minion      :1711][INFO    ][16419] Returning information for job: 20190627030726380700
2019-06-27 03:07:54,084 [salt.state       :300 ][INFO    ][14858] {'ret': True}
2019-06-27 03:07:54,084 [salt.state       :1951][INFO    ][14858] Completed state [maas.wait_for_machine_status] at time 03:07:54.084645 duration_in_ms=725263.628
2019-06-27 03:07:54,091 [salt.minion      :1711][INFO    ][14858] Returning information for job: 20190627025538789227
2019-06-27 03:08:33,088 [salt.utils.schedule:1377][INFO    ][6115] Running scheduled job: __mine_interval
2019-06-27 04:08:33,087 [salt.utils.schedule:1377][INFO    ][6115] Running scheduled job: __mine_interval
2019-06-27 04:39:51,644 [salt.minion      :1308][INFO    ][6115] User sudo_ubuntu Executing command cp.push_dir with jid 20190627043951628665
2019-06-27 04:39:51,671 [salt.minion      :1432][INFO    ][22939] Starting a new job with PID 22939
