2019-05-13 02:04:15,186 [salt.utils.decorators:613 ][WARNING ][1968] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:04:16,150 [salt.utils.decorators:613 ][WARNING ][1968] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:04:19,209 [salt.loaded.int.states.file:2298][WARNING ][2536] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-13 02:04:51,382 [salt.state       :2022][WARNING ][3114] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-13 02:04:53,480 [salt.utils.decorators:613 ][WARNING ][3114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:05:06,343 [salt.utils.decorators:613 ][WARNING ][3114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:05:33,298 [salt.utils.decorators:613 ][WARNING ][3114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:05:34,108 [salt.utils.decorators:613 ][WARNING ][3114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:05:36,256 [salt.loaded.ext.module.maasng:1008][WARNING ][3114] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-13 02:05:36,256 [salt.loaded.ext.module.maasng:1011][WARNING ][3114] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-13 02:05:36,884 [salt.loaded.ext.module.maasng:1235][WARNING ][3114] Ignoring parameter vlan:0
2019-05-13 02:05:46,768 [salt.utils.decorators:613 ][WARNING ][5612] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:05:46,837 [salt.loaded.ext.module.maas:412 ][WARNING ][5612] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-13 02:05:48,036 [salt.loaded.ext.module.maas:412 ][WARNING ][5612] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-13 02:05:49,253 [salt.loaded.ext.module.maas:412 ][WARNING ][5612] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-13 02:05:50,372 [salt.loaded.ext.module.maas:412 ][WARNING ][5612] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-13 02:05:51,501 [salt.loaded.ext.module.maas:412 ][WARNING ][5612] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-13 02:05:54,982 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6182] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-13 02:05:55,007 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6182] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-13 02:05:55,059 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-05-13 02:05:56,064 [salt.cli.daemons :293 ][INFO    ][6255] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-13 02:05:56,204 [salt.cli.daemons :82  ][INFO    ][6255] Starting up the Salt Minion
2019-05-13 02:05:56,205 [salt.utils.event :1017][INFO    ][6255] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-13 02:05:57,386 [salt.minion      :976 ][INFO    ][6255] Creating minion process manager
2019-05-13 02:05:59,235 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6255] Executing command ['date', '+%z'] in directory '/root'
2019-05-13 02:05:59,260 [salt.utils.schedule:568 ][INFO    ][6255] Updating job settings for scheduled job: __mine_interval
2019-05-13 02:05:59,262 [salt.minion      :1108][INFO    ][6255] Added mine.update to scheduler
2019-05-13 02:05:59,266 [salt.minion      :1975][INFO    ][6255] Minion is starting as user 'root'
2019-05-13 02:05:59,288 [salt.minion      :2336][INFO    ][6255] Minion is ready to receive requests!
2019-05-13 02:06:23,671 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513020623655890
2019-05-13 02:06:23,692 [salt.minion      :1432][INFO    ][6363] Starting a new job with PID 6363
2019-05-13 02:06:29,625 [salt.state       :915 ][INFO    ][6363] Loading fresh modules for state activity
2019-05-13 02:06:29,685 [salt.fileclient  :1219][INFO    ][6363] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-13 02:06:29,728 [salt.state       :1780][INFO    ][6363] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:29.728632
2019-05-13 02:06:29,728 [salt.state       :1813][INFO    ][6363] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-13 02:06:29,731 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6363] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-13 02:06:31,478 [salt.state       :300 ][INFO    ][6363] {'pid': 6376, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-13 02:06:31,479 [salt.state       :1951][INFO    ][6363] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:31.479551 duration_in_ms=1750.92
2019-05-13 02:06:31,481 [salt.state       :1780][INFO    ][6363] Running state [maas.wait_for_machine_status] at time 02:06:31.481174
2019-05-13 02:06:31,481 [salt.state       :1813][INFO    ][6363] Executing state module.run for [maas.wait_for_machine_status]
2019-05-13 02:06:31,482 [salt.utils.decorators:613 ][WARNING ][6363] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:06:32,688 [salt.loaded.ext.module.maas:1023][INFO    ][6363] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.80105495s left)
2019-05-13 02:06:38,696 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513020638687072
2019-05-13 02:06:38,720 [salt.minion      :1432][INFO    ][6387] Starting a new job with PID 6387
2019-05-13 02:06:38,747 [salt.minion      :1711][INFO    ][6387] Returning information for job: 20190513020638687072
2019-05-13 02:07:03,542 [salt.loaded.ext.module.maas:1023][INFO    ][6363] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.94718289s left)
2019-05-13 02:07:08,797 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513020708785308
2019-05-13 02:07:08,821 [salt.minion      :1432][INFO    ][6472] Starting a new job with PID 6472
2019-05-13 02:07:08,843 [salt.minion      :1711][INFO    ][6472] Returning information for job: 20190513020708785308
2019-05-13 02:07:34,415 [salt.loaded.ext.module.maas:1023][INFO    ][6363] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.07389998s left)
2019-05-13 02:07:38,889 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513020738874141
2019-05-13 02:07:38,916 [salt.minion      :1432][INFO    ][6498] Starting a new job with PID 6498
2019-05-13 02:07:38,939 [salt.minion      :1711][INFO    ][6498] Returning information for job: 20190513020738874141
2019-05-13 02:08:05,164 [salt.loaded.ext.module.maas:1023][INFO    ][6363] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.3252759s left)
2019-05-13 02:08:08,962 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513020808951532
2019-05-13 02:08:08,988 [salt.minion      :1432][INFO    ][6552] Starting a new job with PID 6552
2019-05-13 02:08:09,013 [salt.minion      :1711][INFO    ][6552] Returning information for job: 20190513020808951532
2019-05-13 02:08:36,247 [salt.loaded.ext.module.maas:1023][INFO    ][6363] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.24172497s left)
2019-05-13 02:08:39,052 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513020839036850
2019-05-13 02:08:39,076 [salt.minion      :1432][INFO    ][6603] Starting a new job with PID 6603
2019-05-13 02:08:39,100 [salt.minion      :1711][INFO    ][6603] Returning information for job: 20190513020839036850
2019-05-13 02:09:07,561 [salt.loaded.ext.module.maas:1023][INFO    ][6363] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.92860889s left)
2019-05-13 02:09:09,152 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513020909138171
2019-05-13 02:09:09,175 [salt.minion      :1432][INFO    ][6770] Starting a new job with PID 6770
2019-05-13 02:09:09,198 [salt.minion      :1711][INFO    ][6770] Returning information for job: 20190513020909138171
2019-05-13 02:09:38,836 [salt.loaded.ext.module.maas:1023][INFO    ][6363] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.65360689s left)
2019-05-13 02:09:39,238 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513020939227424
2019-05-13 02:09:39,269 [salt.minion      :1432][INFO    ][6883] Starting a new job with PID 6883
2019-05-13 02:09:39,291 [salt.minion      :1711][INFO    ][6883] Returning information for job: 20190513020939227424
2019-05-13 02:10:09,380 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021009371024
2019-05-13 02:10:09,395 [salt.minion      :1432][INFO    ][7164] Starting a new job with PID 7164
2019-05-13 02:10:09,418 [salt.minion      :1711][INFO    ][7164] Returning information for job: 20190513021009371024
2019-05-13 02:10:10,240 [salt.loaded.ext.module.maas:1023][INFO    ][6363] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.24950099s left)
2019-05-13 02:10:39,469 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021039455847
2019-05-13 02:10:39,493 [salt.minion      :1432][INFO    ][7297] Starting a new job with PID 7297
2019-05-13 02:10:39,517 [salt.minion      :1711][INFO    ][7297] Returning information for job: 20190513021039455847
2019-05-13 02:10:42,203 [salt.loaded.ext.module.maas:1023][INFO    ][6363] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.28656387s left)
2019-05-13 02:11:09,636 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021109624458
2019-05-13 02:11:09,657 [salt.minion      :1432][INFO    ][7746] Starting a new job with PID 7746
2019-05-13 02:11:09,680 [salt.minion      :1711][INFO    ][7746] Returning information for job: 20190513021109624458
2019-05-13 02:11:14,451 [salt.loaded.ext.module.maas:1023][INFO    ][6363] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.03774405s left)
2019-05-13 02:11:39,754 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021139738780
2019-05-13 02:11:39,784 [salt.minion      :1432][INFO    ][7877] Starting a new job with PID 7877
2019-05-13 02:11:39,804 [salt.minion      :1711][INFO    ][7877] Returning information for job: 20190513021139738780
2019-05-13 02:11:46,606 [salt.loaded.ext.module.maas:1023][INFO    ][6363] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.88314486s left)
2019-05-13 02:12:09,887 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021209871335
2019-05-13 02:12:09,911 [salt.minion      :1432][INFO    ][8018] Starting a new job with PID 8018
2019-05-13 02:12:09,937 [salt.minion      :1711][INFO    ][8018] Returning information for job: 20190513021209871335
2019-05-13 02:12:19,491 [salt.state       :300 ][INFO    ][6363] {'ret': True}
2019-05-13 02:12:19,491 [salt.state       :1951][INFO    ][6363] Completed state [maas.wait_for_machine_status] at time 02:12:19.491860 duration_in_ms=348010.685
2019-05-13 02:12:19,494 [salt.minion      :1711][INFO    ][6363] Returning information for job: 20190513020623655890
2019-05-13 02:12:20,159 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513021220146812
2019-05-13 02:12:20,188 [salt.minion      :1432][INFO    ][8049] Starting a new job with PID 8049
2019-05-13 02:12:26,249 [salt.state       :915 ][INFO    ][8049] Loading fresh modules for state activity
2019-05-13 02:12:26,309 [salt.fileclient  :1219][INFO    ][8049] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-13 02:12:26,404 [salt.state       :1780][INFO    ][8049] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:26.404349
2019-05-13 02:12:26,404 [salt.state       :1813][INFO    ][8049] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-13 02:12:26,406 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8049] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-13 02:12:28,116 [salt.state       :300 ][INFO    ][8049] {'pid': 8064, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-13 02:12:28,117 [salt.state       :1951][INFO    ][8049] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:28.117538 duration_in_ms=1713.188
2019-05-13 02:12:28,121 [salt.state       :1780][INFO    ][8049] Running state [maas_machines_storage_cmp002_lvm] at time 02:12:28.121269
2019-05-13 02:12:28,121 [salt.state       :1813][INFO    ][8049] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-13 02:12:29,223 [salt.loaded.ext.module.maasng:610 ][INFO    ][8049] xccaqr
2019-05-13 02:12:29,223 [salt.loaded.ext.module.maasng:626 ][INFO    ][8049] sda
2019-05-13 02:12:29,805 [salt.loaded.ext.module.maasng:361 ][INFO    ][8049] xccaqr
2019-05-13 02:12:29,881 [salt.loaded.ext.module.maasng:367 ][INFO    ][8049] [{u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'17b45499-9aee-4f14-b604-bce48966373a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xccaqr', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'bdf60c60-f0e7-470d-a7b0-626ee3885f12', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'xccaqr', u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/blockdevices/1/', u'available_size': 0, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'1ea04a1c-d28d-44d2-83eb-c5e364afa58c', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'228cfb92-e80a-45da-96a9-fc5f99a0f535', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'xccaqr', u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/blockdevices/3/', u'available_size': 0, u'id': 3, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2019-05-13 02:12:29,881 [salt.loaded.ext.module.maasng:632 ][INFO    ][8049] vgroot
2019-05-13 02:12:29,882 [salt.loaded.ext.module.maasng:635 ][INFO    ][8049] lvroot
2019-05-13 02:12:29,882 [salt.loaded.ext.module.maasng:639 ][INFO    ][8049] 107374182400
2019-05-13 02:12:30,440 [salt.loaded.ext.module.maasng:645 ][INFO    ][8049] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'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'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'1d944c0c-4ee6-4682-8181-13149cd3beb3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xccaqr', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'e3785642-bb84-4d8a-b984-30db138e14ab', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'xccaqr', u'partition_table_type': u'MBR', u'available_size': 0, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/blockdevices/1/', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'blockdevice_set': [{u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'1d944c0c-4ee6-4682-8181-13149cd3beb3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xccaqr', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'e3785642-bb84-4d8a-b984-30db138e14ab', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'xccaqr', u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/blockdevices/1/', u'available_size': 0, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ba2442b4-9aac-4bdb-9e88-3aceec9d87b2', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'89db33f5-0612-4a01-ace3-0dc52bfc6a59', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'xccaqr', u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/blockdevices/11/', u'available_size': 0, u'id': 11, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'xccaqr', u'power_state': u'off', 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'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ba2442b4-9aac-4bdb-9e88-3aceec9d87b2', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'89db33f5-0612-4a01-ace3-0dc52bfc6a59', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'xccaqr', u'partition_table_type': None, u'available_size': 0, u'id': 11, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/blockdevices/11/', u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xccaqr', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'1d944c0c-4ee6-4682-8181-13149cd3beb3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xccaqr', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'e3785642-bb84-4d8a-b984-30db138e14ab', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'xccaqr', u'partition_table_type': u'MBR', u'available_size': 0, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/blockdevices/1/', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {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'primary_rack': u'qnt3yd', u'fabric': u'pxe_admin', 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'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'primary_rack': u'qnt3yd', u'fabric': u'pxe_admin', 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'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'mac_address': u'9c:b6:54:8a:10:18', 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'primary_rack': u'qnt3yd', u'fabric': u'pxe_admin', 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'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'system_id': u'xccaqr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/interfaces/4/'}, u'interface_set': [{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'primary_rack': u'qnt3yd', u'fabric': u'pxe_admin', 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'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'primary_rack': u'qnt3yd', u'fabric': u'pxe_admin', 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'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'mac_address': u'9c:b6:54:8a:10:18', 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'primary_rack': u'qnt3yd', u'fabric': u'pxe_admin', 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'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'system_id': u'xccaqr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'xccaqr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/interfaces/14/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'xccaqr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/interfaces/11/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'xccaqr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/interfaces/15/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'xccaqr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/interfaces/12/'}, {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'primary_rack': u'qnt3yd', u'fabric': u'pxe_admin', 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'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'primary_rack': u'qnt3yd', u'fabric': u'pxe_admin', 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'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'mac_address': u'9c:b6:54:8a:10:1c', 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'primary_rack': u'qnt3yd', u'fabric': u'pxe_admin', 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'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'system_id': u'xccaqr', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/xccaqr/interfaces/13/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/xccaqr/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-05-13 02:12:30,443 [salt.state       :300 ][INFO    ][8049] {'new': {'storage_layout': 'lvm'}}
2019-05-13 02:12:30,443 [salt.state       :1951][INFO    ][8049] Completed state [maas_machines_storage_cmp002_lvm] at time 02:12:30.443638 duration_in_ms=2322.368
2019-05-13 02:12:30,444 [salt.state       :1780][INFO    ][8049] Running state [maas_machines_storage_cmp001_lvm] at time 02:12:30.444179
2019-05-13 02:12:30,444 [salt.state       :1813][INFO    ][8049] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-13 02:12:31,523 [salt.loaded.ext.module.maasng:610 ][INFO    ][8049] c7xmmm
2019-05-13 02:12:31,524 [salt.loaded.ext.module.maasng:626 ][INFO    ][8049] sda
2019-05-13 02:12:32,099 [salt.loaded.ext.module.maasng:361 ][INFO    ][8049] c7xmmm
2019-05-13 02:12:32,190 [salt.loaded.ext.module.maasng:367 ][INFO    ][8049] [{u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'8dcbcf85-8101-4678-af8e-3673367c677f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c7xmmm', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'0821c4df-d476-41b2-9414-3ea134f9f800', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'c7xmmm', u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/blockdevices/2/', u'available_size': 0, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'b0254b29-6a01-40d1-afd6-b4ebd32c8c6d', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'4fa3a7a6-482c-4fc4-ab14-0b9a42d0ab58', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'c7xmmm', u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/blockdevices/4/', u'available_size': 0, u'id': 4, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 800097042432}]
2019-05-13 02:12:32,191 [salt.loaded.ext.module.maasng:632 ][INFO    ][8049] vgroot
2019-05-13 02:12:32,191 [salt.loaded.ext.module.maasng:635 ][INFO    ][8049] lvroot
2019-05-13 02:12:32,191 [salt.loaded.ext.module.maasng:639 ][INFO    ][8049] 107374182400
2019-05-13 02:12:32,795 [salt.loaded.ext.module.maasng:645 ][INFO    ][8049] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', 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'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/c7xmmm/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'address_ttl': None, u'system_id': u'c7xmmm', u'power_state': u'off', 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'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'c7xmmm', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'c3c059f2-d32a-49f1-a96b-8206fe1e08f5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c7xmmm', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3bfbd6e6-57a0-47ba-a2dd-13c99d6c7678', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/blockdevices/12/', u'type': u'virtual', u'tags': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'48669867-cf6d-4104-8e42-e1842afd1bff', u'fstype': u'ext4'}, u'uuid': u'62e93d76-53c9-4d86-be06-43b9b6f5ad9c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'c7xmmm', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'c7xmmm', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'c3c059f2-d32a-49f1-a96b-8206fe1e08f5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c7xmmm', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3bfbd6e6-57a0-47ba-a2dd-13c99d6c7678', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/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'c7xmmm', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'c7xmmm', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'c3c059f2-d32a-49f1-a96b-8206fe1e08f5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c7xmmm', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3bfbd6e6-57a0-47ba-a2dd-13c99d6c7678', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'48669867-cf6d-4104-8e42-e1842afd1bff', u'fstype': u'ext4'}, u'uuid': u'62e93d76-53c9-4d86-be06-43b9b6f5ad9c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'c7xmmm', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/blockdevices/12/', u'type': u'virtual', u'id': 12, u'partitions': []}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'min_hwe_kernel': u'ga-16.04', 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'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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.39', u'id': 24, u'mode': u'dhcp'}], 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'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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.39'}], u'parents': [], u'system_id': u'c7xmmm', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/interfaces/5/'}, 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'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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.39', u'id': 24, u'mode': u'dhcp'}], 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'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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.39'}], u'parents': [], u'system_id': u'c7xmmm', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 16, u'discovered': None, u'parents': [], u'system_id': u'c7xmmm', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/interfaces/16/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 19, u'discovered': None, u'parents': [], u'system_id': u'c7xmmm', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'mac_address': u'38:ea:a7:8f:52:cc', u'id': 17, u'discovered': None, u'parents': [], u'system_id': u'c7xmmm', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/interfaces/17/'}, {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'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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'id': 26, u'mode': u'link_up'}], 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'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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'mac_address': u'9c:b6:54:8a:95:a4', u'id': 18, 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'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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.41'}], u'parents': [], u'system_id': u'c7xmmm', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'mac_address': u'38:ea:a7:8f:52:cd', u'id': 20, u'discovered': None, u'parents': [], u'system_id': u'c7xmmm', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/c7xmmm/interfaces/20/'}], u'commissioning_status_name': u'Passed', u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-05-13 02:12:32,797 [salt.state       :300 ][INFO    ][8049] {'new': {'storage_layout': 'lvm'}}
2019-05-13 02:12:32,797 [salt.state       :1951][INFO    ][8049] Completed state [maas_machines_storage_cmp001_lvm] at time 02:12:32.797252 duration_in_ms=2353.072
2019-05-13 02:12:32,801 [salt.minion      :1711][INFO    ][8049] Returning information for job: 20190513021220146812
2019-05-13 02:12:33,363 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513021233352009
2019-05-13 02:12:33,389 [salt.minion      :1432][INFO    ][8097] Starting a new job with PID 8097
2019-05-13 02:12:34,339 [salt.state       :915 ][INFO    ][8097] Loading fresh modules for state activity
2019-05-13 02:12:34,402 [salt.fileclient  :1219][INFO    ][8097] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-13 02:12:34,453 [salt.state       :1780][INFO    ][8097] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:34.452986
2019-05-13 02:12:34,453 [salt.state       :1813][INFO    ][8097] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-13 02:12:34,455 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8097] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-13 02:12:36,199 [salt.state       :300 ][INFO    ][8097] {'pid': 8104, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-13 02:12:36,200 [salt.state       :1951][INFO    ][8097] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:36.200022 duration_in_ms=1747.035
2019-05-13 02:12:36,203 [salt.state       :1780][INFO    ][8097] Running state [maas.deploy_machines] at time 02:12:36.203331
2019-05-13 02:12:36,203 [salt.state       :1813][INFO    ][8097] Executing state module.run for [maas.deploy_machines]
2019-05-13 02:12:36,204 [salt.utils.decorators:613 ][WARNING ][8097] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:12:36,786 [salt.loaded.ext.module.maas:684 ][INFO    ][8097] deploymachines hwe_kernel=ga-16.04 system_id=xccaqr distro_series=xenial
2019-05-13 02:12:39,360 [salt.loaded.ext.module.maas:684 ][INFO    ][8097] deploymachines hwe_kernel=ga-16.04 system_id=c7xmmm distro_series=xenial
2019-05-13 02:12:41,924 [salt.loaded.ext.module.maas:684 ][INFO    ][8097] deploymachines hwe_kernel=ga-16.04 system_id=bg834g distro_series=xenial
2019-05-13 02:12:44,465 [salt.loaded.ext.module.maas:684 ][INFO    ][8097] deploymachines hwe_kernel=ga-16.04 system_id=ayhpk4 distro_series=xenial
2019-05-13 02:12:47,120 [salt.loaded.ext.module.maas:684 ][INFO    ][8097] deploymachines hwe_kernel=ga-16.04 system_id=mby3ce distro_series=xenial
2019-05-13 02:12:48,392 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021248377056
2019-05-13 02:12:48,428 [salt.minion      :1432][INFO    ][8370] Starting a new job with PID 8370
2019-05-13 02:12:48,452 [salt.minion      :1711][INFO    ][8370] Returning information for job: 20190513021248377056
2019-05-13 02:12:49,625 [salt.state       :300 ][INFO    ][8097] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-13 02:12:49,626 [salt.state       :1951][INFO    ][8097] Completed state [maas.deploy_machines] at time 02:12:49.626219 duration_in_ms=13422.887
2019-05-13 02:12:49,630 [salt.minion      :1711][INFO    ][8097] Returning information for job: 20190513021233352009
2019-05-13 02:12:50,183 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513021250170342
2019-05-13 02:12:50,213 [salt.minion      :1432][INFO    ][8389] Starting a new job with PID 8389
2019-05-13 02:12:56,384 [salt.state       :915 ][INFO    ][8389] Loading fresh modules for state activity
2019-05-13 02:12:56,443 [salt.fileclient  :1219][INFO    ][8389] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-13 02:12:56,490 [salt.state       :1780][INFO    ][8389] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:56.490504
2019-05-13 02:12:56,490 [salt.state       :1813][INFO    ][8389] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-13 02:12:56,492 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8389] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-13 02:12:58,309 [salt.state       :300 ][INFO    ][8389] {'pid': 8436, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-13 02:12:58,311 [salt.state       :1951][INFO    ][8389] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:58.310911 duration_in_ms=1820.406
2019-05-13 02:12:58,314 [salt.state       :1780][INFO    ][8389] Running state [maas.wait_for_machine_status] at time 02:12:58.314698
2019-05-13 02:12:58,315 [salt.state       :1813][INFO    ][8389] Executing state module.run for [maas.wait_for_machine_status]
2019-05-13 02:12:58,316 [salt.utils.decorators:613 ][WARNING ][8389] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:13:01,578 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.7505939s left)
2019-05-13 02:13:05,242 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021305226588
2019-05-13 02:13:05,275 [salt.minion      :1432][INFO    ][8453] Starting a new job with PID 8453
2019-05-13 02:13:05,299 [salt.minion      :1711][INFO    ][8453] Returning information for job: 20190513021305226588
2019-05-13 02:13:34,413 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.9157958s left)
2019-05-13 02:13:35,325 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021335313381
2019-05-13 02:13:35,347 [salt.minion      :1432][INFO    ][8496] Starting a new job with PID 8496
2019-05-13 02:13:35,369 [salt.minion      :1711][INFO    ][8496] Returning information for job: 20190513021335313381
2019-05-13 02:14:05,407 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021405396453
2019-05-13 02:14:05,432 [salt.minion      :1432][INFO    ][8548] Starting a new job with PID 8548
2019-05-13 02:14:05,453 [salt.minion      :1711][INFO    ][8548] Returning information for job: 20190513021405396453
2019-05-13 02:14:07,388 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.94118881s left)
2019-05-13 02:14:35,488 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021435472534
2019-05-13 02:14:35,514 [salt.minion      :1432][INFO    ][8572] Starting a new job with PID 8572
2019-05-13 02:14:35,532 [salt.minion      :1711][INFO    ][8572] Returning information for job: 20190513021435472534
2019-05-13 02:14:40,158 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.17099881s left)
2019-05-13 02:15:05,556 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021505547286
2019-05-13 02:15:05,585 [salt.minion      :1432][INFO    ][8645] Starting a new job with PID 8645
2019-05-13 02:15:05,607 [salt.minion      :1711][INFO    ][8645] Returning information for job: 20190513021505547286
2019-05-13 02:15:13,119 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.20955992s left)
2019-05-13 02:15:35,644 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021535630666
2019-05-13 02:15:35,662 [salt.minion      :1432][INFO    ][8695] Starting a new job with PID 8695
2019-05-13 02:15:35,682 [salt.minion      :1711][INFO    ][8695] Returning information for job: 20190513021535630666
2019-05-13 02:15:45,924 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.40464497s left)
2019-05-13 02:16:05,746 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021605725398
2019-05-13 02:16:05,779 [salt.minion      :1432][INFO    ][8842] Starting a new job with PID 8842
2019-05-13 02:16:05,805 [salt.minion      :1711][INFO    ][8842] Returning information for job: 20190513021605725398
2019-05-13 02:16:19,429 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.90014887s left)
2019-05-13 02:16:35,868 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021635854228
2019-05-13 02:16:35,901 [salt.minion      :1432][INFO    ][8940] Starting a new job with PID 8940
2019-05-13 02:16:35,922 [salt.minion      :1711][INFO    ][8940] Returning information for job: 20190513021635854228
2019-05-13 02:16:52,374 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.95433879s left)
2019-05-13 02:17:06,010 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021705995460
2019-05-13 02:17:06,042 [salt.minion      :1432][INFO    ][9191] Starting a new job with PID 9191
2019-05-13 02:17:06,063 [salt.minion      :1711][INFO    ][9191] Returning information for job: 20190513021705995460
2019-05-13 02:17:25,444 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.88524699s left)
2019-05-13 02:17:36,127 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021736113374
2019-05-13 02:17:36,156 [salt.minion      :1432][INFO    ][9295] Starting a new job with PID 9295
2019-05-13 02:17:36,177 [salt.minion      :1711][INFO    ][9295] Returning information for job: 20190513021736113374
2019-05-13 02:17:58,950 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.37842894s left)
2019-05-13 02:18:06,295 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021806280912
2019-05-13 02:18:06,324 [salt.minion      :1432][INFO    ][9633] Starting a new job with PID 9633
2019-05-13 02:18:06,347 [salt.minion      :1711][INFO    ][9633] Returning information for job: 20190513021806280912
2019-05-13 02:18:31,853 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.47607279s left)
2019-05-13 02:18:36,436 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021836418967
2019-05-13 02:18:36,462 [salt.minion      :1432][INFO    ][9784] Starting a new job with PID 9784
2019-05-13 02:18:36,488 [salt.minion      :1711][INFO    ][9784] Returning information for job: 20190513021836418967
2019-05-13 02:19:04,854 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.47485185s left)
2019-05-13 02:19:06,581 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021906570218
2019-05-13 02:19:06,607 [salt.minion      :1432][INFO    ][9986] Starting a new job with PID 9986
2019-05-13 02:19:06,628 [salt.minion      :1711][INFO    ][9986] Returning information for job: 20190513021906570218
2019-05-13 02:19:36,716 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513021936705261
2019-05-13 02:19:36,741 [salt.minion      :1432][INFO    ][10065] Starting a new job with PID 10065
2019-05-13 02:19:36,764 [salt.minion      :1711][INFO    ][10065] Returning information for job: 20190513021936705261
2019-05-13 02:19:38,031 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.29736495s left)
2019-05-13 02:20:06,889 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022006872144
2019-05-13 02:20:06,920 [salt.minion      :1432][INFO    ][10315] Starting a new job with PID 10315
2019-05-13 02:20:06,943 [salt.minion      :1711][INFO    ][10315] Returning information for job: 20190513022006872144
2019-05-13 02:20:10,901 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.42809081s left)
2019-05-13 02:20:37,041 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022037023725
2019-05-13 02:20:37,068 [salt.minion      :1432][INFO    ][10401] Starting a new job with PID 10401
2019-05-13 02:20:37,090 [salt.minion      :1711][INFO    ][10401] Returning information for job: 20190513022037023725
2019-05-13 02:20:43,746 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.58235288s left)
2019-05-13 02:21:07,209 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022107191251
2019-05-13 02:21:07,235 [salt.minion      :1432][INFO    ][10532] Starting a new job with PID 10532
2019-05-13 02:21:07,255 [salt.minion      :1711][INFO    ][10532] Returning information for job: 20190513022107191251
2019-05-13 02:21:16,504 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.82498884s left)
2019-05-13 02:21:37,332 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022137312024
2019-05-13 02:21:37,360 [salt.minion      :1432][INFO    ][10614] Starting a new job with PID 10614
2019-05-13 02:21:37,383 [salt.minion      :1711][INFO    ][10614] Returning information for job: 20190513022137312024
2019-05-13 02:21:49,282 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.04698086s left)
2019-05-13 02:22:07,516 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022207506815
2019-05-13 02:22:07,535 [salt.minion      :1432][INFO    ][10787] Starting a new job with PID 10787
2019-05-13 02:22:07,557 [salt.minion      :1711][INFO    ][10787] Returning information for job: 20190513022207506815
2019-05-13 02:22:22,275 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.05341601s left)
2019-05-13 02:22:37,542 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022237527283
2019-05-13 02:22:37,570 [salt.minion      :1432][INFO    ][10862] Starting a new job with PID 10862
2019-05-13 02:22:37,590 [salt.minion      :1711][INFO    ][10862] Returning information for job: 20190513022237527283
2019-05-13 02:22:55,620 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.70915079s left)
2019-05-13 02:23:07,739 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022307719295
2019-05-13 02:23:07,773 [salt.minion      :1432][INFO    ][11078] Starting a new job with PID 11078
2019-05-13 02:23:07,801 [salt.minion      :1711][INFO    ][11078] Returning information for job: 20190513022307719295
2019-05-13 02:23:28,536 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.79291892s left)
2019-05-13 02:23:37,910 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022337894790
2019-05-13 02:23:37,938 [salt.minion      :1432][INFO    ][11142] Starting a new job with PID 11142
2019-05-13 02:23:37,960 [salt.minion      :1711][INFO    ][11142] Returning information for job: 20190513022337894790
2019-05-13 02:24:01,264 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1587.06476998s left)
2019-05-13 02:24:08,095 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022408078741
2019-05-13 02:24:08,122 [salt.minion      :1432][INFO    ][11192] Starting a new job with PID 11192
2019-05-13 02:24:08,142 [salt.minion      :1711][INFO    ][11192] Returning information for job: 20190513022408078741
2019-05-13 02:24:34,061 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1554.26803899s left)
2019-05-13 02:24:38,263 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022438253926
2019-05-13 02:24:38,290 [salt.minion      :1432][INFO    ][11215] Starting a new job with PID 11215
2019-05-13 02:24:38,313 [salt.minion      :1711][INFO    ][11215] Returning information for job: 20190513022438253926
2019-05-13 02:25:06,860 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1521.46893883s left)
2019-05-13 02:25:08,459 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022508444676
2019-05-13 02:25:08,484 [salt.minion      :1432][INFO    ][11410] Starting a new job with PID 11410
2019-05-13 02:25:08,505 [salt.minion      :1711][INFO    ][11410] Returning information for job: 20190513022508444676
2019-05-13 02:25:38,477 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022538467642
2019-05-13 02:25:38,492 [salt.minion      :1432][INFO    ][11506] Starting a new job with PID 11506
2019-05-13 02:25:38,515 [salt.minion      :1711][INFO    ][11506] Returning information for job: 20190513022538467642
2019-05-13 02:25:39,837 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1488.49210596s left)
2019-05-13 02:26:08,576 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022608564405
2019-05-13 02:26:08,602 [salt.minion      :1432][INFO    ][11647] Starting a new job with PID 11647
2019-05-13 02:26:08,626 [salt.minion      :1711][INFO    ][11647] Returning information for job: 20190513022608564405
2019-05-13 02:26:12,820 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1455.50832295s left)
2019-05-13 02:26:38,611 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022638595403
2019-05-13 02:26:38,640 [salt.minion      :1432][INFO    ][11735] Starting a new job with PID 11735
2019-05-13 02:26:38,665 [salt.minion      :1711][INFO    ][11735] Returning information for job: 20190513022638595403
2019-05-13 02:26:45,514 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1422.81502485s left)
2019-05-13 02:27:08,636 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022708619994
2019-05-13 02:27:08,660 [salt.minion      :1432][INFO    ][11784] Starting a new job with PID 11784
2019-05-13 02:27:08,694 [salt.minion      :1711][INFO    ][11784] Returning information for job: 20190513022708619994
2019-05-13 02:27:18,501 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1389.82824302s left)
2019-05-13 02:27:38,705 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022738691897
2019-05-13 02:27:38,728 [salt.minion      :1432][INFO    ][11807] Starting a new job with PID 11807
2019-05-13 02:27:38,747 [salt.minion      :1711][INFO    ][11807] Returning information for job: 20190513022738691897
2019-05-13 02:27:51,346 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1356.98315692s left)
2019-05-13 02:28:08,741 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022808728913
2019-05-13 02:28:08,770 [salt.minion      :1432][INFO    ][11857] Starting a new job with PID 11857
2019-05-13 02:28:08,793 [salt.minion      :1711][INFO    ][11857] Returning information for job: 20190513022808728913
2019-05-13 02:28:24,150 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1324.17856479s left)
2019-05-13 02:28:38,832 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022838818112
2019-05-13 02:28:38,860 [salt.minion      :1432][INFO    ][11883] Starting a new job with PID 11883
2019-05-13 02:28:38,885 [salt.minion      :1711][INFO    ][11883] Returning information for job: 20190513022838818112
2019-05-13 02:28:57,152 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1291.17638493s left)
2019-05-13 02:29:08,917 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022908903118
2019-05-13 02:29:08,946 [salt.minion      :1432][INFO    ][11934] Starting a new job with PID 11934
2019-05-13 02:29:08,967 [salt.minion      :1711][INFO    ][11934] Returning information for job: 20190513022908903118
2019-05-13 02:29:29,957 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1258.37193489s left)
2019-05-13 02:29:39,008 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513022938992160
2019-05-13 02:29:39,033 [salt.minion      :1432][INFO    ][11957] Starting a new job with PID 11957
2019-05-13 02:29:39,055 [salt.minion      :1711][INFO    ][11957] Returning information for job: 20190513022938992160
2019-05-13 02:30:02,604 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1225.724823s left)
2019-05-13 02:30:09,120 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023009103801
2019-05-13 02:30:09,150 [salt.minion      :1432][INFO    ][12012] Starting a new job with PID 12012
2019-05-13 02:30:09,172 [salt.minion      :1711][INFO    ][12012] Returning information for job: 20190513023009103801
2019-05-13 02:30:35,589 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1192.73974586s left)
2019-05-13 02:30:39,246 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023039229089
2019-05-13 02:30:39,279 [salt.minion      :1432][INFO    ][12039] Starting a new job with PID 12039
2019-05-13 02:30:39,300 [salt.minion      :1711][INFO    ][12039] Returning information for job: 20190513023039229089
2019-05-13 02:31:08,108 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1160.22061801s left)
2019-05-13 02:31:09,368 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023109359955
2019-05-13 02:31:09,391 [salt.minion      :1432][INFO    ][12091] Starting a new job with PID 12091
2019-05-13 02:31:09,414 [salt.minion      :1711][INFO    ][12091] Returning information for job: 20190513023109359955
2019-05-13 02:31:39,517 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023139506842
2019-05-13 02:31:39,540 [salt.minion      :1432][INFO    ][12111] Starting a new job with PID 12111
2019-05-13 02:31:39,564 [salt.minion      :1711][INFO    ][12111] Returning information for job: 20190513023139506842
2019-05-13 02:31:41,216 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1127.11292386s left)
2019-05-13 02:32:09,676 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023209662285
2019-05-13 02:32:09,704 [salt.minion      :1432][INFO    ][12161] Starting a new job with PID 12161
2019-05-13 02:32:09,727 [salt.minion      :1711][INFO    ][12161] Returning information for job: 20190513023209662285
2019-05-13 02:32:13,837 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1094.49182582s left)
2019-05-13 02:32:39,823 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023239806612
2019-05-13 02:32:39,850 [salt.minion      :1432][INFO    ][12184] Starting a new job with PID 12184
2019-05-13 02:32:39,873 [salt.minion      :1711][INFO    ][12184] Returning information for job: 20190513023239806612
2019-05-13 02:32:46,651 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1061.67765594s left)
2019-05-13 02:33:10,018 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023310009428
2019-05-13 02:33:10,047 [salt.minion      :1432][INFO    ][12236] Starting a new job with PID 12236
2019-05-13 02:33:10,068 [salt.minion      :1711][INFO    ][12236] Returning information for job: 20190513023310009428
2019-05-13 02:33:19,523 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1028.80621099s left)
2019-05-13 02:33:40,221 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023340209356
2019-05-13 02:33:40,252 [salt.minion      :1432][INFO    ][12260] Starting a new job with PID 12260
2019-05-13 02:33:40,277 [salt.minion      :1711][INFO    ][12260] Returning information for job: 20190513023340209356
2019-05-13 02:33:52,503 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (995.82565999s left)
2019-05-13 02:34:10,252 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023410236873
2019-05-13 02:34:10,275 [salt.minion      :1432][INFO    ][12314] Starting a new job with PID 12314
2019-05-13 02:34:10,296 [salt.minion      :1711][INFO    ][12314] Returning information for job: 20190513023410236873
2019-05-13 02:34:25,392 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (962.936753988s left)
2019-05-13 02:34:40,464 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023440448582
2019-05-13 02:34:40,492 [salt.minion      :1432][INFO    ][12338] Starting a new job with PID 12338
2019-05-13 02:34:40,519 [salt.minion      :1711][INFO    ][12338] Returning information for job: 20190513023440448582
2019-05-13 02:34:58,320 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (930.00865984s left)
2019-05-13 02:35:10,533 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023510515604
2019-05-13 02:35:10,561 [salt.minion      :1432][INFO    ][12417] Starting a new job with PID 12417
2019-05-13 02:35:10,588 [salt.minion      :1711][INFO    ][12417] Returning information for job: 20190513023510515604
2019-05-13 02:35:30,904 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (897.42493701s left)
2019-05-13 02:35:40,580 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023540564514
2019-05-13 02:35:40,611 [salt.minion      :1432][INFO    ][12450] Starting a new job with PID 12450
2019-05-13 02:35:40,633 [salt.minion      :1711][INFO    ][12450] Returning information for job: 20190513023540564514
2019-05-13 02:36:03,648 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (864.680346966s left)
2019-05-13 02:36:10,674 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023610660093
2019-05-13 02:36:10,701 [salt.minion      :1432][INFO    ][12502] Starting a new job with PID 12502
2019-05-13 02:36:10,727 [salt.minion      :1711][INFO    ][12502] Returning information for job: 20190513023610660093
2019-05-13 02:36:36,499 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (831.829356909s left)
2019-05-13 02:36:40,749 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023640733263
2019-05-13 02:36:40,775 [salt.minion      :1432][INFO    ][12526] Starting a new job with PID 12526
2019-05-13 02:36:40,798 [salt.minion      :1711][INFO    ][12526] Returning information for job: 20190513023640733263
2019-05-13 02:37:09,423 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (798.907720804s left)
2019-05-13 02:37:10,856 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023710846297
2019-05-13 02:37:10,884 [salt.minion      :1432][INFO    ][12577] Starting a new job with PID 12577
2019-05-13 02:37:10,909 [salt.minion      :1711][INFO    ][12577] Returning information for job: 20190513023710846297
2019-05-13 02:37:41,037 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023741024297
2019-05-13 02:37:41,062 [salt.minion      :1432][INFO    ][12610] Starting a new job with PID 12610
2019-05-13 02:37:41,096 [salt.minion      :1711][INFO    ][12610] Returning information for job: 20190513023741024297
2019-05-13 02:37:42,207 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (766.121492863s left)
2019-05-13 02:38:11,177 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023811161296
2019-05-13 02:38:11,203 [salt.minion      :1432][INFO    ][12655] Starting a new job with PID 12655
2019-05-13 02:38:11,226 [salt.minion      :1711][INFO    ][12655] Returning information for job: 20190513023811161296
2019-05-13 02:38:14,956 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (733.373044968s left)
2019-05-13 02:38:41,368 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023841356259
2019-05-13 02:38:41,392 [salt.minion      :1432][INFO    ][12693] Starting a new job with PID 12693
2019-05-13 02:38:41,415 [salt.minion      :1711][INFO    ][12693] Returning information for job: 20190513023841356259
2019-05-13 02:38:48,015 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (700.313609838s left)
2019-05-13 02:39:11,516 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023911503632
2019-05-13 02:39:11,545 [salt.minion      :1432][INFO    ][12735] Starting a new job with PID 12735
2019-05-13 02:39:11,566 [salt.minion      :1711][INFO    ][12735] Returning information for job: 20190513023911503632
2019-05-13 02:39:20,746 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (667.582927942s left)
2019-05-13 02:39:41,732 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513023941721706
2019-05-13 02:39:41,753 [salt.minion      :1432][INFO    ][12768] Starting a new job with PID 12768
2019-05-13 02:39:41,777 [salt.minion      :1711][INFO    ][12768] Returning information for job: 20190513023941721706
2019-05-13 02:39:53,576 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (634.752391815s left)
2019-05-13 02:40:11,922 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024011909304
2019-05-13 02:40:11,947 [salt.minion      :1432][INFO    ][12813] Starting a new job with PID 12813
2019-05-13 02:40:11,969 [salt.minion      :1711][INFO    ][12813] Returning information for job: 20190513024011909304
2019-05-13 02:40:26,376 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (601.952596903s left)
2019-05-13 02:40:41,991 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024041974709
2019-05-13 02:40:42,017 [salt.minion      :1432][INFO    ][12845] Starting a new job with PID 12845
2019-05-13 02:40:42,040 [salt.minion      :1711][INFO    ][12845] Returning information for job: 20190513024041974709
2019-05-13 02:40:59,160 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (569.16870594s left)
2019-05-13 02:41:12,060 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024112045279
2019-05-13 02:41:12,088 [salt.minion      :1432][INFO    ][12887] Starting a new job with PID 12887
2019-05-13 02:41:12,111 [salt.minion      :1711][INFO    ][12887] Returning information for job: 20190513024112045279
2019-05-13 02:41:32,076 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (536.252548933s left)
2019-05-13 02:41:42,161 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024142144446
2019-05-13 02:41:42,190 [salt.minion      :1432][INFO    ][12918] Starting a new job with PID 12918
2019-05-13 02:41:42,213 [salt.minion      :1711][INFO    ][12918] Returning information for job: 20190513024142144446
2019-05-13 02:42:04,788 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (503.540525913s left)
2019-05-13 02:42:12,261 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024212253226
2019-05-13 02:42:12,285 [salt.minion      :1432][INFO    ][12962] Starting a new job with PID 12962
2019-05-13 02:42:12,308 [salt.minion      :1711][INFO    ][12962] Returning information for job: 20190513024212253226
2019-05-13 02:42:37,548 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (470.780490875s left)
2019-05-13 02:42:42,407 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024242392148
2019-05-13 02:42:42,432 [salt.minion      :1432][INFO    ][12996] Starting a new job with PID 12996
2019-05-13 02:42:42,459 [salt.minion      :1711][INFO    ][12996] Returning information for job: 20190513024242392148
2019-05-13 02:43:10,472 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (437.857198s left)
2019-05-13 02:43:12,544 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024312531301
2019-05-13 02:43:12,569 [salt.minion      :1432][INFO    ][13038] Starting a new job with PID 13038
2019-05-13 02:43:12,593 [salt.minion      :1711][INFO    ][13038] Returning information for job: 20190513024312531301
2019-05-13 02:43:42,704 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024342689077
2019-05-13 02:43:42,730 [salt.minion      :1432][INFO    ][13071] Starting a new job with PID 13071
2019-05-13 02:43:42,762 [salt.minion      :1711][INFO    ][13071] Returning information for job: 20190513024342689077
2019-05-13 02:43:43,270 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (405.058525801s left)
2019-05-13 02:44:12,900 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024412886481
2019-05-13 02:44:12,930 [salt.minion      :1432][INFO    ][13112] Starting a new job with PID 13112
2019-05-13 02:44:12,952 [salt.minion      :1711][INFO    ][13112] Returning information for job: 20190513024412886481
2019-05-13 02:44:16,180 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (372.148425817s left)
2019-05-13 02:44:43,128 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024443118689
2019-05-13 02:44:43,147 [salt.minion      :1432][INFO    ][13277] Starting a new job with PID 13277
2019-05-13 02:44:43,175 [salt.minion      :1711][INFO    ][13277] Returning information for job: 20190513024443118689
2019-05-13 02:44:49,100 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (339.229138851s left)
2019-05-13 02:45:13,165 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024513151044
2019-05-13 02:45:13,192 [salt.minion      :1432][INFO    ][13337] Starting a new job with PID 13337
2019-05-13 02:45:13,215 [salt.minion      :1711][INFO    ][13337] Returning information for job: 20190513024513151044
2019-05-13 02:45:21,719 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (306.610018015s left)
2019-05-13 02:45:43,356 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024543340036
2019-05-13 02:45:43,380 [salt.minion      :1432][INFO    ][13370] Starting a new job with PID 13370
2019-05-13 02:45:43,404 [salt.minion      :1711][INFO    ][13370] Returning information for job: 20190513024543340036
2019-05-13 02:45:54,708 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (273.620898962s left)
2019-05-13 02:46:13,435 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024613420461
2019-05-13 02:46:13,462 [salt.minion      :1432][INFO    ][13413] Starting a new job with PID 13413
2019-05-13 02:46:13,485 [salt.minion      :1711][INFO    ][13413] Returning information for job: 20190513024613420461
2019-05-13 02:46:27,476 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (240.852277994s left)
2019-05-13 02:46:43,635 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024643618492
2019-05-13 02:46:43,661 [salt.minion      :1432][INFO    ][13455] Starting a new job with PID 13455
2019-05-13 02:46:43,690 [salt.minion      :1711][INFO    ][13455] Returning information for job: 20190513024643618492
2019-05-13 02:47:00,325 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (208.004218817s left)
2019-05-13 02:47:13,751 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024713738717
2019-05-13 02:47:13,774 [salt.minion      :1432][INFO    ][13496] Starting a new job with PID 13496
2019-05-13 02:47:13,799 [salt.minion      :1711][INFO    ][13496] Returning information for job: 20190513024713738717
2019-05-13 02:47:33,239 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (175.089666843s left)
2019-05-13 02:47:43,779 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024743767064
2019-05-13 02:47:43,804 [salt.minion      :1432][INFO    ][13528] Starting a new job with PID 13528
2019-05-13 02:47:43,827 [salt.minion      :1711][INFO    ][13528] Returning information for job: 20190513024743767064
2019-05-13 02:48:06,024 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (142.305291891s left)
2019-05-13 02:48:13,924 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024813907129
2019-05-13 02:48:13,948 [salt.minion      :1432][INFO    ][13572] Starting a new job with PID 13572
2019-05-13 02:48:13,972 [salt.minion      :1711][INFO    ][13572] Returning information for job: 20190513024813907129
2019-05-13 02:48:38,816 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (109.512427807s left)
2019-05-13 02:48:44,046 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024844031917
2019-05-13 02:48:44,073 [salt.minion      :1432][INFO    ][13606] Starting a new job with PID 13606
2019-05-13 02:48:44,095 [salt.minion      :1711][INFO    ][13606] Returning information for job: 20190513024844031917
2019-05-13 02:49:11,544 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (76.7848348618s left)
2019-05-13 02:49:14,200 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024914184775
2019-05-13 02:49:14,231 [salt.minion      :1432][INFO    ][13648] Starting a new job with PID 13648
2019-05-13 02:49:14,254 [salt.minion      :1711][INFO    ][13648] Returning information for job: 20190513024914184775
2019-05-13 02:49:44,362 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (43.9667348862s left)
2019-05-13 02:49:44,396 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513024944384218
2019-05-13 02:49:44,419 [salt.minion      :1432][INFO    ][13681] Starting a new job with PID 13681
2019-05-13 02:49:44,443 [salt.minion      :1711][INFO    ][13681] Returning information for job: 20190513024944384218
2019-05-13 02:50:14,598 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025014585158
2019-05-13 02:50:14,621 [salt.minion      :1432][INFO    ][13722] Starting a new job with PID 13722
2019-05-13 02:50:14,645 [salt.minion      :1711][INFO    ][13722] Returning information for job: 20190513025014585158
2019-05-13 02:50:17,304 [salt.loaded.ext.module.maas:1023][INFO    ][8389] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (11.0247910023s left)
2019-05-13 02:50:44,648 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025044636775
2019-05-13 02:50:44,675 [salt.minion      :1432][INFO    ][13756] Starting a new job with PID 13756
2019-05-13 02:50:44,699 [salt.minion      :1711][INFO    ][13756] Returning information for job: 20190513025044636775
2019-05-13 02:50:50,194 [salt.state       :302 ][ERROR   ][8389] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-13 02:50:50,195 [salt.state       :1951][INFO    ][8389] Completed state [maas.wait_for_machine_status] at time 02:50:50.195412 duration_in_ms=2271880.709
2019-05-13 02:50:50,201 [salt.minion      :1711][INFO    ][8389] Returning information for job: 20190513021250170342
2019-05-13 02:51:00,996 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command pillar.get with jid 20190513025100987821
2019-05-13 02:51:01,024 [salt.minion      :1432][INFO    ][13796] Starting a new job with PID 13796
2019-05-13 02:51:01,035 [salt.minion      :1711][INFO    ][13796] Returning information for job: 20190513025100987821
2019-05-13 02:51:01,599 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command service.status with jid 20190513025101584969
2019-05-13 02:51:01,627 [salt.minion      :1432][INFO    ][13801] Starting a new job with PID 13801
2019-05-13 02:51:02,169 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13801] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-13 02:51:02,203 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13801] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-13 02:51:02,224 [salt.minion      :1711][INFO    ][13801] Returning information for job: 20190513025101584969
2019-05-13 02:51:02,801 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513025102785707
2019-05-13 02:51:02,828 [salt.minion      :1432][INFO    ][13812] Starting a new job with PID 13812
2019-05-13 02:51:08,883 [salt.state       :915 ][INFO    ][13812] Loading fresh modules for state activity
2019-05-13 02:51:09,501 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command 'salt-minion --version' in directory '/root'
2019-05-13 02:51:09,804 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command 'salt-minion --version' in directory '/root'
2019-05-13 02:51:10,832 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command 'salt-minion --version' in directory '/root'
2019-05-13 02:51:11,136 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command 'salt-minion --version' in directory '/root'
2019-05-13 02:51:13,021 [salt.state       :1780][INFO    ][13812] Running state [salt-minion] at time 02:51:13.021452
2019-05-13 02:51:13,022 [salt.state       :1813][INFO    ][13812] Executing state pkg.installed for [salt-minion]
2019-05-13 02:51:13,023 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-13 02:51:13,148 [salt.state       :300 ][INFO    ][13812] All specified packages are already installed
2019-05-13 02:51:13,148 [salt.state       :1951][INFO    ][13812] Completed state [salt-minion] at time 02:51:13.148722 duration_in_ms=127.271
2019-05-13 02:51:13,149 [salt.state       :1780][INFO    ][13812] Running state [salt_minion_dependency_packages] at time 02:51:13.149066
2019-05-13 02:51:13,149 [salt.state       :1813][INFO    ][13812] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-13 02:51:13,160 [salt.state       :300 ][INFO    ][13812] All specified packages are already installed
2019-05-13 02:51:13,160 [salt.state       :1951][INFO    ][13812] Completed state [salt_minion_dependency_packages] at time 02:51:13.160412 duration_in_ms=11.347
2019-05-13 02:51:13,163 [salt.state       :1780][INFO    ][13812] Running state [/etc/salt/minion.d/minion.conf] at time 02:51:13.163580
2019-05-13 02:51:13,163 [salt.state       :1813][INFO    ][13812] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-13 02:51:13,444 [salt.state       :300 ][INFO    ][13812] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-13 02:51:13,444 [salt.state       :1951][INFO    ][13812] Completed state [/etc/salt/minion.d/minion.conf] at time 02:51:13.444712 duration_in_ms=281.132
2019-05-13 02:51:13,445 [salt.state       :1780][INFO    ][13812] Running state [python-netaddr] at time 02:51:13.444983
2019-05-13 02:51:13,445 [salt.state       :1813][INFO    ][13812] Executing state pkg.installed for [python-netaddr]
2019-05-13 02:51:13,454 [salt.state       :300 ][INFO    ][13812] All specified packages are already installed
2019-05-13 02:51:13,454 [salt.state       :1951][INFO    ][13812] Completed state [python-netaddr] at time 02:51:13.454531 duration_in_ms=9.548
2019-05-13 02:51:13,458 [salt.state       :1780][INFO    ][13812] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:13.457704
2019-05-13 02:51:13,458 [salt.state       :1813][INFO    ][13812] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-13 02:51:13,471 [salt.state       :300 ][INFO    ][13812] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-13 02:51:13,471 [salt.state       :1951][INFO    ][13812] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:13.471781 duration_in_ms=14.077
2019-05-13 02:51:13,473 [salt.state       :1780][INFO    ][13812] Running state [salt-minion] at time 02:51:13.472971
2019-05-13 02:51:13,473 [salt.state       :1813][INFO    ][13812] Executing state service.running for [salt-minion]
2019-05-13 02:51:13,473 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-13 02:51:13,518 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-13 02:51:13,540 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-13 02:51:13,565 [salt.state       :300 ][INFO    ][13812] The service salt-minion is already running
2019-05-13 02:51:13,565 [salt.state       :1951][INFO    ][13812] Completed state [salt-minion] at time 02:51:13.565569 duration_in_ms=92.595
2019-05-13 02:51:13,568 [salt.state       :1780][INFO    ][13812] Running state [/etc/salt/grains.d] at time 02:51:13.568531
2019-05-13 02:51:13,569 [salt.state       :1813][INFO    ][13812] Executing state file.directory for [/etc/salt/grains.d]
2019-05-13 02:51:13,570 [salt.state       :300 ][INFO    ][13812] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-13 02:51:13,571 [salt.state       :1951][INFO    ][13812] Completed state [/etc/salt/grains.d] at time 02:51:13.571161 duration_in_ms=2.63
2019-05-13 02:51:13,572 [salt.state       :1780][INFO    ][13812] Running state [/etc/salt/grains] at time 02:51:13.572414
2019-05-13 02:51:13,572 [salt.state       :1813][INFO    ][13812] Executing state file.managed for [/etc/salt/grains]
2019-05-13 02:51:13,575 [salt.state       :300 ][INFO    ][13812] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-13 02:51:13,575 [salt.state       :1951][INFO    ][13812] Completed state [/etc/salt/grains] at time 02:51:13.575797 duration_in_ms=3.384
2019-05-13 02:51:13,576 [salt.state       :1780][INFO    ][13812] Running state [/etc/salt/grains.d/placeholder] at time 02:51:13.576271
2019-05-13 02:51:13,576 [salt.state       :1813][INFO    ][13812] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-13 02:51:13,577 [salt.state       :300 ][INFO    ][13812] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-13 02:51:13,577 [salt.state       :1951][INFO    ][13812] Completed state [/etc/salt/grains.d/placeholder] at time 02:51:13.577207 duration_in_ms=0.935
2019-05-13 02:51:13,577 [salt.state       :1780][INFO    ][13812] Running state [/etc/salt/grains.d/sphinx] at time 02:51:13.577682
2019-05-13 02:51:13,577 [salt.state       :1813][INFO    ][13812] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-13 02:51:13,579 [salt.state       :300 ][INFO    ][13812] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-13 02:51:13,579 [salt.state       :1951][INFO    ][13812] Completed state [/etc/salt/grains.d/sphinx] at time 02:51:13.579886 duration_in_ms=2.203
2019-05-13 02:51:13,583 [salt.state       :1780][INFO    ][13812] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:13.583389
2019-05-13 02:51:13,583 [salt.state       :1813][INFO    ][13812] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-13 02:51:13,584 [salt.state       :300 ][INFO    ][13812] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-13 02:51:13,584 [salt.state       :1951][INFO    ][13812] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:13.584421 duration_in_ms=1.033
2019-05-13 02:51:13,585 [salt.state       :1780][INFO    ][13812] Running state [/etc/salt/grains.d/dns_records] at time 02:51:13.584979
2019-05-13 02:51:13,585 [salt.state       :1813][INFO    ][13812] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-13 02:51:13,587 [salt.state       :300 ][INFO    ][13812] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-13 02:51:13,588 [salt.state       :1951][INFO    ][13812] Completed state [/etc/salt/grains.d/dns_records] at time 02:51:13.588184 duration_in_ms=3.205
2019-05-13 02:51:13,589 [salt.state       :1780][INFO    ][13812] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:13.589289
2019-05-13 02:51:13,589 [salt.state       :1813][INFO    ][13812] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-13 02:51:13,590 [salt.state       :300 ][INFO    ][13812] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-13 02:51:13,590 [salt.state       :1951][INFO    ][13812] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:13.590504 duration_in_ms=1.215
2019-05-13 02:51:13,591 [salt.state       :1780][INFO    ][13812] Running state [/etc/salt/grains.d/salt] at time 02:51:13.591070
2019-05-13 02:51:13,591 [salt.state       :1813][INFO    ][13812] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-13 02:51:13,592 [salt.state       :300 ][INFO    ][13812] File /etc/salt/grains.d/salt is in the correct state
2019-05-13 02:51:13,592 [salt.state       :1951][INFO    ][13812] Completed state [/etc/salt/grains.d/salt] at time 02:51:13.592621 duration_in_ms=1.551
2019-05-13 02:51:13,593 [salt.state       :1780][INFO    ][13812] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:13.593663
2019-05-13 02:51:13,594 [salt.state       :1813][INFO    ][13812] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-13 02:51:13,594 [salt.state       :300 ][INFO    ][13812] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-13 02:51:13,594 [salt.state       :1951][INFO    ][13812] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:13.594551 duration_in_ms=0.888
2019-05-13 02:51:13,596 [salt.state       :1780][INFO    ][13812] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:13.596824
2019-05-13 02:51:13,597 [salt.state       :1813][INFO    ][13812] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-13 02:51:13,597 [salt.state       :300 ][INFO    ][13812] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-13 02:51:13,599 [salt.state       :1951][INFO    ][13812] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:13.599189 duration_in_ms=2.365
2019-05-13 02:51:13,599 [salt.state       :1780][INFO    ][13812] Running state [mine.update] at time 02:51:13.599868
2019-05-13 02:51:13,600 [salt.state       :1813][INFO    ][13812] Executing state module.wait for [mine.update]
2019-05-13 02:51:13,600 [salt.state       :300 ][INFO    ][13812] No changes made for mine.update
2019-05-13 02:51:13,600 [salt.state       :1951][INFO    ][13812] Completed state [mine.update] at time 02:51:13.600563 duration_in_ms=0.695
2019-05-13 02:51:13,600 [salt.state       :1780][INFO    ][13812] Running state [ca-certificates] at time 02:51:13.600795
2019-05-13 02:51:13,601 [salt.state       :1813][INFO    ][13812] Executing state pkg.installed for [ca-certificates]
2019-05-13 02:51:13,611 [salt.state       :300 ][INFO    ][13812] All specified packages are already installed
2019-05-13 02:51:13,611 [salt.state       :1951][INFO    ][13812] Completed state [ca-certificates] at time 02:51:13.611357 duration_in_ms=10.561
2019-05-13 02:51:13,612 [salt.state       :1780][INFO    ][13812] Running state [update-ca-certificates] at time 02:51:13.612050
2019-05-13 02:51:13,612 [salt.state       :1813][INFO    ][13812] Executing state cmd.wait for [update-ca-certificates]
2019-05-13 02:51:13,612 [salt.state       :300 ][INFO    ][13812] No changes made for update-ca-certificates
2019-05-13 02:51:13,612 [salt.state       :1951][INFO    ][13812] Completed state [update-ca-certificates] at time 02:51:13.612773 duration_in_ms=0.722
2019-05-13 02:51:13,613 [salt.state       :1780][INFO    ][13812] Running state [iptables] at time 02:51:13.613008
2019-05-13 02:51:13,613 [salt.state       :1813][INFO    ][13812] Executing state pkg.installed for [iptables]
2019-05-13 02:51:13,622 [salt.state       :300 ][INFO    ][13812] All specified packages are already installed
2019-05-13 02:51:13,622 [salt.state       :1951][INFO    ][13812] Completed state [iptables] at time 02:51:13.622886 duration_in_ms=9.877
2019-05-13 02:51:13,623 [salt.state       :1780][INFO    ][13812] Running state [iptables-persistent] at time 02:51:13.623128
2019-05-13 02:51:13,623 [salt.state       :1813][INFO    ][13812] Executing state pkg.installed for [iptables-persistent]
2019-05-13 02:51:13,632 [salt.state       :300 ][INFO    ][13812] All specified packages are already installed
2019-05-13 02:51:13,632 [salt.state       :1951][INFO    ][13812] Completed state [iptables-persistent] at time 02:51:13.632212 duration_in_ms=9.084
2019-05-13 02:51:13,634 [salt.state       :1780][INFO    ][13812] Running state [iptables_modules_v4_load] at time 02:51:13.634682
2019-05-13 02:51:13,635 [salt.state       :1813][INFO    ][13812] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-13 02:51:13,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command 'lsmod' in directory '/root'
2019-05-13 02:51:13,661 [salt.state       :300 ][INFO    ][13812] Kernel modules iptable_filter, ip_tables are already present
2019-05-13 02:51:13,661 [salt.state       :1951][INFO    ][13812] Completed state [iptables_modules_v4_load] at time 02:51:13.661910 duration_in_ms=27.229
2019-05-13 02:51:13,663 [salt.state       :1780][INFO    ][13812] Running state [/etc/iptables/rules.v4] at time 02:51:13.663024
2019-05-13 02:51:13,663 [salt.state       :1813][INFO    ][13812] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-13 02:51:13,769 [salt.state       :300 ][INFO    ][13812] File /etc/iptables/rules.v4 is in the correct state
2019-05-13 02:51:13,770 [salt.state       :1951][INFO    ][13812] Completed state [/etc/iptables/rules.v4] at time 02:51:13.770445 duration_in_ms=107.421
2019-05-13 02:51:13,772 [salt.state       :1780][INFO    ][13812] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:13.772424
2019-05-13 02:51:13,772 [salt.state       :1813][INFO    ][13812] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-13 02:51:13,773 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-13 02:51:13,800 [salt.state       :300 ][INFO    ][13812] onlyif execution failed
2019-05-13 02:51:13,800 [salt.state       :1951][INFO    ][13812] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:13.800615 duration_in_ms=28.191
2019-05-13 02:51:13,805 [salt.state       :1780][INFO    ][13812] Running state [netfilter-persistent] at time 02:51:13.805300
2019-05-13 02:51:13,805 [salt.state       :1813][INFO    ][13812] Executing state service.running for [netfilter-persistent]
2019-05-13 02:51:13,806 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-13 02:51:13,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-13 02:51:13,847 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-13 02:51:13,870 [salt.state       :300 ][INFO    ][13812] The service netfilter-persistent is already running
2019-05-13 02:51:13,870 [salt.state       :1951][INFO    ][13812] Completed state [netfilter-persistent] at time 02:51:13.870850 duration_in_ms=65.549
2019-05-13 02:51:13,872 [salt.state       :1780][INFO    ][13812] Running state [iptables_extra.remove_stale_tables] at time 02:51:13.872876
2019-05-13 02:51:13,873 [salt.state       :1813][INFO    ][13812] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-13 02:51:13,874 [salt.state       :300 ][INFO    ][13812] No changes made for iptables_extra.remove_stale_tables
2019-05-13 02:51:13,875 [salt.state       :1951][INFO    ][13812] Completed state [iptables_extra.remove_stale_tables] at time 02:51:13.875184 duration_in_ms=2.308
2019-05-13 02:51:13,875 [salt.state       :1780][INFO    ][13812] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:13.875684
2019-05-13 02:51:13,876 [salt.state       :1813][INFO    ][13812] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-13 02:51:13,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13812] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-13 02:51:13,894 [salt.state       :300 ][INFO    ][13812] onlyif execution failed
2019-05-13 02:51:13,895 [salt.state       :1951][INFO    ][13812] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:13.895260 duration_in_ms=19.576
2019-05-13 02:51:13,896 [salt.state       :1780][INFO    ][13812] Running state [/etc/iptables/rules.v6] at time 02:51:13.896658
2019-05-13 02:51:13,897 [salt.state       :1813][INFO    ][13812] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-13 02:51:13,898 [salt.state       :300 ][INFO    ][13812] File /etc/iptables/rules.v6 is not present
2019-05-13 02:51:13,899 [salt.state       :1951][INFO    ][13812] Completed state [/etc/iptables/rules.v6] at time 02:51:13.899009 duration_in_ms=2.351
2019-05-13 02:51:13,900 [salt.state       :1780][INFO    ][13812] Running state [iptables_extra.flush_all] at time 02:51:13.900047
2019-05-13 02:51:13,900 [salt.state       :1813][INFO    ][13812] Executing state module.wait for [iptables_extra.flush_all]
2019-05-13 02:51:13,900 [salt.state       :300 ][INFO    ][13812] No changes made for iptables_extra.flush_all
2019-05-13 02:51:13,901 [salt.state       :1951][INFO    ][13812] Completed state [iptables_extra.flush_all] at time 02:51:13.901200 duration_in_ms=1.153
2019-05-13 02:51:13,906 [salt.minion      :1711][INFO    ][13812] Returning information for job: 20190513025102785707
2019-05-13 02:51:14,456 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513025114444784
2019-05-13 02:51:14,483 [salt.minion      :1432][INFO    ][13894] Starting a new job with PID 13894
2019-05-13 02:51:15,501 [salt.state       :915 ][INFO    ][13894] Loading fresh modules for state activity
2019-05-13 02:51:16,399 [salt.state       :1780][INFO    ][13894] Running state [maas-rack-controller] at time 02:51:16.398975
2019-05-13 02:51:16,399 [salt.state       :1813][INFO    ][13894] Executing state pkg.installed for [maas-rack-controller]
2019-05-13 02:51:16,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13894] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-13 02:51:16,518 [salt.state       :300 ][INFO    ][13894] All specified packages are already installed
2019-05-13 02:51:16,519 [salt.state       :1951][INFO    ][13894] Completed state [maas-rack-controller] at time 02:51:16.519132 duration_in_ms=120.156
2019-05-13 02:51:16,519 [salt.state       :1780][INFO    ][13894] Running state [ipmitool] at time 02:51:16.519514
2019-05-13 02:51:16,519 [salt.state       :1813][INFO    ][13894] Executing state pkg.installed for [ipmitool]
2019-05-13 02:51:16,529 [salt.state       :300 ][INFO    ][13894] All specified packages are already installed
2019-05-13 02:51:16,529 [salt.state       :1951][INFO    ][13894] Completed state [ipmitool] at time 02:51:16.529578 duration_in_ms=10.064
2019-05-13 02:51:16,534 [salt.state       :1780][INFO    ][13894] Running state [/etc/maas/rackd.conf] at time 02:51:16.533963
2019-05-13 02:51:16,534 [salt.state       :1813][INFO    ][13894] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-13 02:51:16,535 [salt.state       :300 ][INFO    ][13894] No changes needed to be made
2019-05-13 02:51:16,535 [salt.state       :1951][INFO    ][13894] Completed state [/etc/maas/rackd.conf] at time 02:51:16.535394 duration_in_ms=1.431
2019-05-13 02:51:16,535 [salt.state       :1780][INFO    ][13894] Running state [/etc/maas/rackd.conf] at time 02:51:16.535636
2019-05-13 02:51:16,535 [salt.state       :1813][INFO    ][13894] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-13 02:51:16,536 [salt.loaded.int.states.file:2298][WARNING ][13894] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-13 02:51:16,536 [salt.state       :300 ][INFO    ][13894] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-13 02:51:16,537 [salt.state       :1951][INFO    ][13894] Completed state [/etc/maas/rackd.conf] at time 02:51:16.537135 duration_in_ms=1.499
2019-05-13 02:51:16,538 [salt.state       :1780][INFO    ][13894] Running state [maas-rackd] at time 02:51:16.538771
2019-05-13 02:51:16,539 [salt.state       :1813][INFO    ][13894] Executing state service.running for [maas-rackd]
2019-05-13 02:51:16,539 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13894] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-13 02:51:16,581 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13894] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-13 02:51:16,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13894] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-13 02:51:16,625 [salt.state       :300 ][INFO    ][13894] The service maas-rackd is already running
2019-05-13 02:51:16,626 [salt.state       :1951][INFO    ][13894] Completed state [maas-rackd] at time 02:51:16.626474 duration_in_ms=87.702
2019-05-13 02:51:16,629 [salt.minion      :1711][INFO    ][13894] Returning information for job: 20190513025114444784
2019-05-13 02:51:17,190 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513025117173621
2019-05-13 02:51:17,217 [salt.minion      :1432][INFO    ][13917] Starting a new job with PID 13917
2019-05-13 02:51:18,188 [salt.state       :915 ][INFO    ][13917] Loading fresh modules for state activity
2019-05-13 02:51:19,237 [salt.state       :1780][INFO    ][13917] Running state [maas-region-controller] at time 02:51:19.237438
2019-05-13 02:51:19,238 [salt.state       :1813][INFO    ][13917] Executing state pkg.installed for [maas-region-controller]
2019-05-13 02:51:19,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-13 02:51:19,345 [salt.state       :300 ][INFO    ][13917] All specified packages are already installed
2019-05-13 02:51:19,346 [salt.state       :1951][INFO    ][13917] Completed state [maas-region-controller] at time 02:51:19.345685 duration_in_ms=108.248
2019-05-13 02:51:19,346 [salt.state       :1780][INFO    ][13917] Running state [python-oauth] at time 02:51:19.346910
2019-05-13 02:51:19,347 [salt.state       :1813][INFO    ][13917] Executing state pkg.installed for [python-oauth]
2019-05-13 02:51:19,355 [salt.state       :300 ][INFO    ][13917] All specified packages are already installed
2019-05-13 02:51:19,355 [salt.state       :1951][INFO    ][13917] Completed state [python-oauth] at time 02:51:19.355934 duration_in_ms=9.024
2019-05-13 02:51:19,359 [salt.state       :1780][INFO    ][13917] Running state [/etc/maas/regiond.conf] at time 02:51:19.359624
2019-05-13 02:51:19,359 [salt.state       :1813][INFO    ][13917] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-13 02:51:19,364 [salt.state       :300 ][INFO    ][13917] No changes needed to be made
2019-05-13 02:51:19,364 [salt.state       :1951][INFO    ][13917] Completed state [/etc/maas/regiond.conf] at time 02:51:19.364483 duration_in_ms=4.859
2019-05-13 02:51:19,365 [salt.state       :1780][INFO    ][13917] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:19.364972
2019-05-13 02:51:19,365 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-13 02:51:19,431 [salt.state       :300 ][INFO    ][13917] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-13 02:51:19,431 [salt.state       :1951][INFO    ][13917] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:19.431901 duration_in_ms=66.928
2019-05-13 02:51:19,432 [salt.state       :1780][INFO    ][13917] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:19.432397
2019-05-13 02:51:19,432 [salt.state       :1813][INFO    ][13917] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-13 02:51:19,436 [salt.state       :300 ][INFO    ][13917] No changes needed to be made
2019-05-13 02:51:19,436 [salt.state       :1951][INFO    ][13917] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:19.436360 duration_in_ms=3.963
2019-05-13 02:51:19,436 [salt.state       :1780][INFO    ][13917] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:19.436845
2019-05-13 02:51:19,437 [salt.state       :1813][INFO    ][13917] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-13 02:51:19,440 [salt.state       :300 ][INFO    ][13917] No changes needed to be made
2019-05-13 02:51:19,440 [salt.state       :1951][INFO    ][13917] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:19.440678 duration_in_ms=3.834
2019-05-13 02:51:19,441 [salt.state       :1780][INFO    ][13917] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:19.441165
2019-05-13 02:51:19,441 [salt.state       :1813][INFO    ][13917] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-13 02:51:19,464 [salt.state       :300 ][INFO    ][13917] No changes needed to be made
2019-05-13 02:51:19,464 [salt.state       :1951][INFO    ][13917] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:19.464199 duration_in_ms=23.034
2019-05-13 02:51:19,464 [salt.state       :1780][INFO    ][13917] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:19.464684
2019-05-13 02:51:19,464 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-13 02:51:19,483 [salt.state       :300 ][INFO    ][13917] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-13 02:51:19,483 [salt.state       :1951][INFO    ][13917] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:19.483840 duration_in_ms=19.154
2019-05-13 02:51:19,486 [salt.state       :1780][INFO    ][13917] Running state [a2enmod headers] at time 02:51:19.486221
2019-05-13 02:51:19,486 [salt.state       :1813][INFO    ][13917] Executing state cmd.run for [a2enmod headers]
2019-05-13 02:51:19,487 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command 'a2enmod headers' in directory '/root'
2019-05-13 02:51:19,566 [salt.state       :300 ][INFO    ][13917] {'pid': 13936, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-13 02:51:19,566 [salt.state       :1951][INFO    ][13917] Completed state [a2enmod headers] at time 02:51:19.566792 duration_in_ms=80.571
2019-05-13 02:51:19,567 [salt.state       :1780][INFO    ][13917] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:19.567454
2019-05-13 02:51:19,568 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-13 02:51:19,593 [salt.state       :300 ][INFO    ][13917] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-13 02:51:19,594 [salt.state       :1951][INFO    ][13917] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:19.594334 duration_in_ms=26.88
2019-05-13 02:51:19,595 [salt.state       :1780][INFO    ][13917] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:19.595194
2019-05-13 02:51:19,595 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-13 02:51:19,657 [salt.state       :300 ][INFO    ][13917] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-13 02:51:19,658 [salt.state       :1951][INFO    ][13917] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:19.658772 duration_in_ms=63.578
2019-05-13 02:51:19,660 [salt.state       :1780][INFO    ][13917] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:19.659915
2019-05-13 02:51:19,660 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-13 02:51:19,728 [salt.state       :300 ][INFO    ][13917] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-13 02:51:19,728 [salt.state       :1951][INFO    ][13917] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:19.728446 duration_in_ms=68.531
2019-05-13 02:51:19,729 [salt.state       :1780][INFO    ][13917] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:19.728972
2019-05-13 02:51:19,729 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-13 02:51:19,808 [salt.state       :300 ][INFO    ][13917] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-13 02:51:19,809 [salt.state       :1951][INFO    ][13917] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:19.808998 duration_in_ms=80.024
2019-05-13 02:51:19,809 [salt.state       :1780][INFO    ][13917] Running state [/root/.pgpass] at time 02:51:19.809558
2019-05-13 02:51:19,810 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/root/.pgpass]
2019-05-13 02:51:19,864 [salt.state       :300 ][INFO    ][13917] File /root/.pgpass is in the correct state
2019-05-13 02:51:19,864 [salt.state       :1951][INFO    ][13917] Completed state [/root/.pgpass] at time 02:51:19.864730 duration_in_ms=55.172
2019-05-13 02:51:19,873 [salt.state       :1780][INFO    ][13917] Running state [maas-region syncdb --noinput] at time 02:51:19.873438
2019-05-13 02:51:19,875 [salt.state       :1813][INFO    ][13917] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-13 02:51:19,875 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-13 02:51:22,393 [salt.state       :300 ][INFO    ][13917] {'pid': 13949, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, piston3, contenttypes, sites, sessions, maasserver, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-13 02:51:22,394 [salt.state       :1951][INFO    ][13917] Completed state [maas-region syncdb --noinput] at time 02:51:22.394639 duration_in_ms=2521.19
2019-05-13 02:51:22,395 [salt.state       :2022][WARNING ][13917] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-13 02:51:22,399 [salt.state       :1780][INFO    ][13917] Running state [maas-regiond] at time 02:51:22.399006
2019-05-13 02:51:22,399 [salt.state       :1813][INFO    ][13917] Executing state service.running for [maas-regiond]
2019-05-13 02:51:22,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-13 02:51:22,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-13 02:51:22,465 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-13 02:51:22,485 [salt.state       :300 ][INFO    ][13917] The service maas-regiond is already running
2019-05-13 02:51:22,486 [salt.state       :1951][INFO    ][13917] Completed state [maas-regiond] at time 02:51:22.486244 duration_in_ms=87.239
2019-05-13 02:51:22,488 [salt.state       :1780][INFO    ][13917] Running state [bind9] at time 02:51:22.488813
2019-05-13 02:51:22,489 [salt.state       :1813][INFO    ][13917] Executing state service.running for [bind9]
2019-05-13 02:51:22,491 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-13 02:51:22,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-13 02:51:22,530 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-13 02:51:22,552 [salt.state       :300 ][INFO    ][13917] The service bind9 is already running
2019-05-13 02:51:22,553 [salt.state       :1951][INFO    ][13917] Completed state [bind9] at time 02:51:22.552943 duration_in_ms=64.13
2019-05-13 02:51:22,557 [salt.state       :1780][INFO    ][13917] Running state [apache2] at time 02:51:22.557240
2019-05-13 02:51:22,557 [salt.state       :1813][INFO    ][13917] Executing state service.running for [apache2]
2019-05-13 02:51:22,558 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-13 02:51:22,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-13 02:51:22,604 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-13 02:51:22,631 [salt.state       :300 ][INFO    ][13917] The service apache2 is already running
2019-05-13 02:51:22,631 [salt.state       :1951][INFO    ][13917] Completed state [apache2] at time 02:51:22.631871 duration_in_ms=74.631
2019-05-13 02:51:22,637 [salt.state       :1780][INFO    ][13917] Running state [maasng.wait_for_http_code] at time 02:51:22.633701
2019-05-13 02:51:22,637 [salt.state       :1813][INFO    ][13917] Executing state module.run for [maasng.wait_for_http_code]
2019-05-13 02:51:22,638 [salt.utils.decorators:613 ][WARNING ][13917] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:51:22,748 [salt.state       :300 ][INFO    ][13917] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-13 02:51:22,749 [salt.state       :1951][INFO    ][13917] Completed state [maasng.wait_for_http_code] at time 02:51:22.749206 duration_in_ms=115.504
2019-05-13 02:51:22,751 [salt.state       :1780][INFO    ][13917] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:22.751536
2019-05-13 02:51:22,752 [salt.state       :1813][INFO    ][13917] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-13 02:51:22,752 [salt.state       :300 ][INFO    ][13917] /var/lib/maas/.setup_admin exists
2019-05-13 02:51:22,753 [salt.state       :1951][INFO    ][13917] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:22.753243 duration_in_ms=1.707
2019-05-13 02:51:22,755 [salt.state       :1780][INFO    ][13917] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:22.755367
2019-05-13 02:51:22,755 [salt.state       :1813][INFO    ][13917] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-13 02:51:22,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-13 02:51:24,511 [salt.state       :300 ][INFO    ][13917] {'pid': 13970, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-13 02:51:24,512 [salt.state       :1951][INFO    ][13917] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:24.512078 duration_in_ms=1756.711
2019-05-13 02:51:24,521 [salt.state       :1780][INFO    ][13917] Running state [maas_region_boot_source_resources_mirror] at time 02:51:24.520981
2019-05-13 02:51:24,521 [salt.state       :1813][INFO    ][13917] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-13 02:51:24,615 [salt.state       :300 ][INFO    ][13917] {'changes': {}}
2019-05-13 02:51:24,615 [salt.state       :1951][INFO    ][13917] Completed state [maas_region_boot_source_resources_mirror] at time 02:51:24.615662 duration_in_ms=94.681
2019-05-13 02:51:24,616 [salt.state       :1780][INFO    ][13917] Running state [maasng.boot_resources_import] at time 02:51:24.616505
2019-05-13 02:51:24,616 [salt.state       :1813][INFO    ][13917] Executing state module.run for [maasng.boot_resources_import]
2019-05-13 02:51:24,617 [salt.utils.decorators:613 ][WARNING ][13917] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:51:24,714 [salt.loaded.ext.module.maasng:1600][INFO    ][13917] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-13 02:51:29,778 [salt.loaded.ext.module.maasng:1600][INFO    ][13917] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-13 02:51:32,257 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025132245409
2019-05-13 02:51:32,280 [salt.minion      :1432][INFO    ][14016] Starting a new job with PID 14016
2019-05-13 02:51:32,302 [salt.minion      :1711][INFO    ][14016] Returning information for job: 20190513025132245409
2019-05-13 02:51:34,879 [salt.state       :300 ][INFO    ][13917] {'ret': True}
2019-05-13 02:51:34,879 [salt.state       :1951][INFO    ][13917] Completed state [maasng.boot_resources_import] at time 02:51:34.879667 duration_in_ms=10263.161
2019-05-13 02:51:34,881 [salt.state       :1780][INFO    ][13917] Running state [maas_region_boot_sources_selection_xenial] at time 02:51:34.880927
2019-05-13 02:51:34,881 [salt.state       :1813][INFO    ][13917] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-13 02:51:35,058 [salt.state       :300 ][INFO    ][13917] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-13 02:51:35,058 [salt.state       :1951][INFO    ][13917] Completed state [maas_region_boot_sources_selection_xenial] at time 02:51:35.058890 duration_in_ms=177.963
2019-05-13 02:51:35,060 [salt.state       :1780][INFO    ][13917] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:51:35.060317
2019-05-13 02:51:35,060 [salt.state       :1813][INFO    ][13917] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-13 02:51:35,061 [salt.utils.decorators:613 ][WARNING ][13917] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:51:35,061 [salt.loaded.ext.module.maasng:1771][INFO    ][13917] boot-sources sync initiated for ALL Rack's
2019-05-13 02:51:36,131 [salt.state       :300 ][INFO    ][13917] {'ret': True}
2019-05-13 02:51:36,132 [salt.state       :1951][INFO    ][13917] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:51:36.132302 duration_in_ms=1071.985
2019-05-13 02:51:36,136 [salt.state       :1780][INFO    ][13917] Running state [maas.process_maas_config] at time 02:51:36.136135
2019-05-13 02:51:36,136 [salt.state       :1813][INFO    ][13917] Executing state module.run for [maas.process_maas_config]
2019-05-13 02:51:36,137 [salt.utils.decorators:613 ][WARNING ][13917] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:51:36,138 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=enable_http_proxy value=True
2019-05-13 02:51:36,192 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=upstream_dns value=8.8.8.8
2019-05-13 02:51:36,247 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=commissioning_distro_series value=xenial
2019-05-13 02:51:36,311 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=default_osystem value=ubuntu
2019-05-13 02:51:36,368 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=active_discovery_interval value=600
2019-05-13 02:51:37,687 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=dnssec_validation value=no
2019-05-13 02:51:37,736 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=maas_name value=mas01
2019-05-13 02:51:37,797 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=network_discovery value=enabled
2019-05-13 02:51:37,891 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=enable_third_party_drivers value=True
2019-05-13 02:51:37,940 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=default_storage_layout value=lvm
2019-05-13 02:51:38,003 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=ntp_external_only value=True
2019-05-13 02:51:38,065 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-13 02:51:38,149 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=default_distro_series value=xenial
2019-05-13 02:51:38,212 [salt.loaded.ext.module.maas:92  ][INFO    ][13917] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-13 02:51:38,352 [salt.state       :300 ][INFO    ][13917] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-13 02:51:38,353 [salt.state       :1951][INFO    ][13917] Completed state [maas.process_maas_config] at time 02:51:38.353137 duration_in_ms=2216.993
2019-05-13 02:51:38,356 [salt.state       :1780][INFO    ][13917] Running state [pxe_admin] at time 02:51:38.356223
2019-05-13 02:51:38,356 [salt.state       :1813][INFO    ][13917] Executing state maasng.fabric_present for [pxe_admin]
2019-05-13 02:51:38,410 [salt.loaded.ext.module.maasng:945 ][INFO    ][13917] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'name': u'fabric-2'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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'name': u'pxe_admin'}]
2019-05-13 02:51:38,473 [salt.loaded.ext.module.maasng:1008][WARNING ][13917] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-13 02:51:38,476 [salt.loaded.ext.module.maasng:1011][WARNING ][13917] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-13 02:51:38,542 [salt.state       :300 ][INFO    ][13917] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-13 02:51:38,542 [salt.state       :1951][INFO    ][13917] Completed state [pxe_admin] at time 02:51:38.542711 duration_in_ms=186.487
2019-05-13 02:51:38,543 [salt.state       :1780][INFO    ][13917] Running state [vlan 0] at time 02:51:38.543079
2019-05-13 02:51:38,543 [salt.state       :1813][INFO    ][13917] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-13 02:51:38,611 [salt.loaded.ext.module.maasng:945 ][INFO    ][13917] [{u'id': 0, u'class_type': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', 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'qnt3yd', 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/'}]
2019-05-13 02:51:38,721 [salt.loaded.ext.module.maasng:945 ][INFO    ][13917] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'name': u'fabric-2'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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'name': u'pxe_admin'}]
2019-05-13 02:51:38,967 [salt.loaded.ext.module.maasng:945 ][INFO    ][13917] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'name': u'fabric-2'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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'name': u'pxe_admin'}]
2019-05-13 02:51:39,043 [salt.state       :300 ][INFO    ][13917] {'new': 'Vlan untagged was updated'}
2019-05-13 02:51:39,043 [salt.state       :1951][INFO    ][13917] Completed state [vlan 0] at time 02:51:39.043851 duration_in_ms=500.771
2019-05-13 02:51:39,045 [salt.state       :1780][INFO    ][13917] Running state [192.168.11.0/24] at time 02:51:39.045054
2019-05-13 02:51:39,045 [salt.state       :1813][INFO    ][13917] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-13 02:51:39,261 [salt.loaded.ext.module.maasng:945 ][INFO    ][13917] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'name': u'fabric-2'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', 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'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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'name': u'pxe_admin'}]
2019-05-13 02:51:39,262 [salt.loaded.ext.module.maasng:1235][WARNING ][13917] Ignoring parameter vlan:0
2019-05-13 02:51:39,334 [salt.state       :300 ][INFO    ][13917] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-13 02:51:39,334 [salt.state       :1951][INFO    ][13917] Completed state [192.168.11.0/24] at time 02:51:39.334588 duration_in_ms=289.533
2019-05-13 02:51:39,335 [salt.state       :1780][INFO    ][13917] Running state [maas_create_iprange_1] at time 02:51:39.335407
2019-05-13 02:51:39,335 [salt.state       :1813][INFO    ][13917] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-13 02:51:39,379 [salt.state       :300 ][INFO    ][13917] Iprange maas_create_iprange_1 already exist.
2019-05-13 02:51:39,379 [salt.state       :1951][INFO    ][13917] Completed state [maas_create_iprange_1] at time 02:51:39.379909 duration_in_ms=44.502
2019-05-13 02:51:39,380 [salt.state       :1780][INFO    ][13917] Running state [vlan 0] at time 02:51:39.380191
2019-05-13 02:51:39,380 [salt.state       :1813][INFO    ][13917] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-13 02:51:39,428 [salt.loaded.ext.module.maasng:945 ][INFO    ][13917] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'qnt3yd', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-13 02:51:39,535 [salt.loaded.ext.module.maasng:945 ][INFO    ][13917] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'name': u'fabric-2'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', 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'primary_rack': u'qnt3yd', u'relay_vlan': None, u'external_dhcp': None, 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'name': u'pxe_admin'}]
2019-05-13 02:51:39,779 [salt.loaded.ext.module.maasng:945 ][INFO    ][13917] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'qnt3yd', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-13 02:51:39,881 [salt.state       :300 ][INFO    ][13917] {'new': 'Vlan untagged was updated'}
2019-05-13 02:51:39,882 [salt.state       :1951][INFO    ][13917] Completed state [vlan 0] at time 02:51:39.882142 duration_in_ms=501.95
2019-05-13 02:51:39,882 [salt.state       :1780][INFO    ][13917] Running state [opnfv] at time 02:51:39.882918
2019-05-13 02:51:39,883 [salt.state       :1813][INFO    ][13917] Executing state maasng.sshkey_present for [opnfv]
2019-05-13 02:51:39,946 [salt.loaded.ext.module.maasng:1903][INFO    ][13917] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-13 02:51:39,946 [salt.state       :300 ][INFO    ][13917] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-13 02:51:39,946 [salt.state       :1951][INFO    ][13917] Completed state [opnfv] at time 02:51:39.946707 duration_in_ms=63.789
2019-05-13 02:51:39,949 [salt.minion      :1711][INFO    ][13917] Returning information for job: 20190513025117173621
2019-05-13 02:51:40,560 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513025140548884
2019-05-13 02:51:40,590 [salt.minion      :1432][INFO    ][14357] Starting a new job with PID 14357
2019-05-13 02:51:46,657 [salt.state       :915 ][INFO    ][14357] Loading fresh modules for state activity
2019-05-13 02:51:46,773 [salt.state       :1780][INFO    ][14357] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:46.773306
2019-05-13 02:51:46,773 [salt.state       :1813][INFO    ][14357] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-13 02:51:46,775 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14357] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-13 02:51:48,495 [salt.state       :300 ][INFO    ][14357] {'pid': 14394, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-13 02:51:48,496 [salt.state       :1951][INFO    ][14357] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:48.496127 duration_in_ms=1722.82
2019-05-13 02:51:48,497 [salt.state       :1780][INFO    ][14357] Running state [maas.process_machines] at time 02:51:48.497798
2019-05-13 02:51:48,498 [salt.state       :1813][INFO    ][14357] Executing state module.run for [maas.process_machines]
2019-05-13 02:51:48,498 [salt.utils.decorators:613 ][WARNING ][14357] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:51:49,073 [salt.loaded.ext.module.maas:412 ][WARNING ][14357] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-13 02:51:49,073 [salt.loaded.ext.module.maas:92  ][INFO    ][14357] 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=xccaqr architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-13 02:51:50,305 [salt.loaded.ext.module.maas:412 ][WARNING ][14357] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-13 02:51:50,307 [salt.loaded.ext.module.maas:92  ][INFO    ][14357] 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=c7xmmm architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-13 02:51:51,516 [salt.loaded.ext.module.maas:412 ][WARNING ][14357] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-13 02:51:51,519 [salt.loaded.ext.module.maas:92  ][INFO    ][14357] 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=bg834g architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-13 02:51:52,751 [salt.loaded.ext.module.maas:412 ][WARNING ][14357] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-13 02:51:52,752 [salt.loaded.ext.module.maas:92  ][INFO    ][14357] 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=ayhpk4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-13 02:51:53,981 [salt.loaded.ext.module.maas:412 ][WARNING ][14357] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-13 02:51:53,982 [salt.loaded.ext.module.maas:92  ][INFO    ][14357] 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=mby3ce architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-13 02:51:55,344 [salt.state       :300 ][INFO    ][14357] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-13 02:51:55,344 [salt.state       :1951][INFO    ][14357] Completed state [maas.process_machines] at time 02:51:55.344908 duration_in_ms=6847.109
2019-05-13 02:51:55,348 [salt.minion      :1711][INFO    ][14357] Returning information for job: 20190513025140548884
2019-05-13 02:52:28,390 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513025228375825
2019-05-13 02:52:28,422 [salt.minion      :1432][INFO    ][14653] Starting a new job with PID 14653
2019-05-13 02:52:34,310 [salt.state       :915 ][INFO    ][14653] Loading fresh modules for state activity
2019-05-13 02:52:34,421 [salt.state       :1780][INFO    ][14653] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:34.421362
2019-05-13 02:52:34,421 [salt.state       :1813][INFO    ][14653] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-13 02:52:34,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14653] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-13 02:52:36,147 [salt.state       :300 ][INFO    ][14653] {'pid': 14660, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-13 02:52:36,148 [salt.state       :1951][INFO    ][14653] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:36.148516 duration_in_ms=1727.154
2019-05-13 02:52:36,151 [salt.state       :1780][INFO    ][14653] Running state [maas.wait_for_machine_status] at time 02:52:36.151736
2019-05-13 02:52:36,152 [salt.state       :1813][INFO    ][14653] Executing state module.run for [maas.wait_for_machine_status]
2019-05-13 02:52:36,152 [salt.utils.decorators:613 ][WARNING ][14653] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:52:38,920 [salt.state       :300 ][INFO    ][14653] {'ret': True}
2019-05-13 02:52:38,920 [salt.state       :1951][INFO    ][14653] Completed state [maas.wait_for_machine_status] at time 02:52:38.920296 duration_in_ms=2768.559
2019-05-13 02:52:38,922 [salt.minion      :1711][INFO    ][14653] Returning information for job: 20190513025228375825
2019-05-13 02:52:39,478 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513025239465863
2019-05-13 02:52:39,504 [salt.minion      :1432][INFO    ][14673] Starting a new job with PID 14673
2019-05-13 02:52:40,493 [salt.state       :915 ][INFO    ][14673] Loading fresh modules for state activity
2019-05-13 02:52:40,639 [salt.state       :1780][INFO    ][14673] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:40.639648
2019-05-13 02:52:40,640 [salt.state       :1813][INFO    ][14673] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-13 02:52:40,641 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14673] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-13 02:52:42,403 [salt.state       :300 ][INFO    ][14673] {'pid': 14680, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-13 02:52:42,404 [salt.state       :1951][INFO    ][14673] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:42.403977 duration_in_ms=1764.329
2019-05-13 02:52:42,406 [salt.state       :1780][INFO    ][14673] Running state [maas_machines_storage_cmp002_lvm] at time 02:52:42.406581
2019-05-13 02:52:42,406 [salt.state       :1813][INFO    ][14673] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-13 02:52:43,067 [salt.state       :300 ][INFO    ][14673] Machine cmp002 is not in Ready state.
2019-05-13 02:52:43,067 [salt.state       :1951][INFO    ][14673] Completed state [maas_machines_storage_cmp002_lvm] at time 02:52:43.067696 duration_in_ms=661.113
2019-05-13 02:52:43,068 [salt.state       :1780][INFO    ][14673] Running state [maas_machines_storage_cmp001_lvm] at time 02:52:43.068451
2019-05-13 02:52:43,069 [salt.state       :1813][INFO    ][14673] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-13 02:52:43,612 [salt.state       :300 ][INFO    ][14673] Machine cmp001 is not in Ready state.
2019-05-13 02:52:43,613 [salt.state       :1951][INFO    ][14673] Completed state [maas_machines_storage_cmp001_lvm] at time 02:52:43.613072 duration_in_ms=544.622
2019-05-13 02:52:43,616 [salt.minion      :1711][INFO    ][14673] Returning information for job: 20190513025239465863
2019-05-13 02:52:44,147 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513025244135274
2019-05-13 02:52:44,177 [salt.minion      :1432][INFO    ][14703] Starting a new job with PID 14703
2019-05-13 02:52:45,207 [salt.state       :915 ][INFO    ][14703] Loading fresh modules for state activity
2019-05-13 02:52:45,308 [salt.state       :1780][INFO    ][14703] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:45.308069
2019-05-13 02:52:45,308 [salt.state       :1813][INFO    ][14703] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-13 02:52:45,310 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14703] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-13 02:52:47,019 [salt.state       :300 ][INFO    ][14703] {'pid': 14710, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-13 02:52:47,020 [salt.state       :1951][INFO    ][14703] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:47.020498 duration_in_ms=1712.428
2019-05-13 02:52:47,024 [salt.state       :1780][INFO    ][14703] Running state [maas.deploy_machines] at time 02:52:47.024201
2019-05-13 02:52:47,024 [salt.state       :1813][INFO    ][14703] Executing state module.run for [maas.deploy_machines]
2019-05-13 02:52:47,025 [salt.utils.decorators:613 ][WARNING ][14703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:52:47,575 [salt.loaded.ext.module.maas:684 ][INFO    ][14703] deploymachines hwe_kernel=ga-16.04 system_id=mby3ce distro_series=xenial
2019-05-13 02:52:50,021 [salt.state       :300 ][INFO    ][14703] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-13 02:52:50,022 [salt.state       :1951][INFO    ][14703] Completed state [maas.deploy_machines] at time 02:52:50.022428 duration_in_ms=2998.227
2019-05-13 02:52:50,025 [salt.minion      :1711][INFO    ][14703] Returning information for job: 20190513025244135274
2019-05-13 02:52:50,577 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command state.apply with jid 20190513025250566864
2019-05-13 02:52:50,601 [salt.minion      :1432][INFO    ][14769] Starting a new job with PID 14769
2019-05-13 02:52:56,718 [salt.state       :915 ][INFO    ][14769] Loading fresh modules for state activity
2019-05-13 02:52:56,816 [salt.state       :1780][INFO    ][14769] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:56.816668
2019-05-13 02:52:56,816 [salt.state       :1813][INFO    ][14769] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-13 02:52:56,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14769] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-13 02:52:58,552 [salt.state       :300 ][INFO    ][14769] {'pid': 14810, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-13 02:52:58,554 [salt.state       :1951][INFO    ][14769] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:58.554447 duration_in_ms=1737.78
2019-05-13 02:52:58,557 [salt.state       :1780][INFO    ][14769] Running state [maas.wait_for_machine_status] at time 02:52:58.557141
2019-05-13 02:52:58,557 [salt.state       :1813][INFO    ][14769] Executing state module.run for [maas.wait_for_machine_status]
2019-05-13 02:52:58,558 [salt.utils.decorators:613 ][WARNING ][14769] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-13 02:53:01,424 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.15637016s left)
2019-05-13 02:53:05,664 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025305656130
2019-05-13 02:53:05,686 [salt.minion      :1432][INFO    ][14821] Starting a new job with PID 14821
2019-05-13 02:53:05,708 [salt.minion      :1711][INFO    ][14821] Returning information for job: 20190513025305656130
2019-05-13 02:53:34,388 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.19272804s left)
2019-05-13 02:53:35,734 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025335678625
2019-05-13 02:53:35,761 [salt.minion      :1432][INFO    ][14850] Starting a new job with PID 14850
2019-05-13 02:53:35,788 [salt.minion      :1711][INFO    ][14850] Returning information for job: 20190513025335678625
2019-05-13 02:54:05,836 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025405825033
2019-05-13 02:54:05,855 [salt.minion      :1432][INFO    ][14899] Starting a new job with PID 14899
2019-05-13 02:54:05,880 [salt.minion      :1711][INFO    ][14899] Returning information for job: 20190513025405825033
2019-05-13 02:54:07,123 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.45790315s left)
2019-05-13 02:54:35,899 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025435880946
2019-05-13 02:54:35,927 [salt.minion      :1432][INFO    ][14921] Starting a new job with PID 14921
2019-05-13 02:54:35,949 [salt.minion      :1711][INFO    ][14921] Returning information for job: 20190513025435880946
2019-05-13 02:54:39,896 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.68482304s left)
2019-05-13 02:55:05,993 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025505972893
2019-05-13 02:55:06,022 [salt.minion      :1432][INFO    ][14997] Starting a new job with PID 14997
2019-05-13 02:55:06,046 [salt.minion      :1711][INFO    ][14997] Returning information for job: 20190513025505972893
2019-05-13 02:55:12,708 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.87253904s left)
2019-05-13 02:55:36,073 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025536055881
2019-05-13 02:55:36,102 [salt.minion      :1432][INFO    ][15024] Starting a new job with PID 15024
2019-05-13 02:55:36,126 [salt.minion      :1711][INFO    ][15024] Returning information for job: 20190513025536055881
2019-05-13 02:55:45,558 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.02214718s left)
2019-05-13 02:56:06,172 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025606158695
2019-05-13 02:56:06,203 [salt.minion      :1432][INFO    ][15079] Starting a new job with PID 15079
2019-05-13 02:56:06,227 [salt.minion      :1711][INFO    ][15079] Returning information for job: 20190513025606158695
2019-05-13 02:56:18,355 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.22551799s left)
2019-05-13 02:56:36,268 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025636251407
2019-05-13 02:56:36,299 [salt.minion      :1432][INFO    ][15110] Starting a new job with PID 15110
2019-05-13 02:56:36,321 [salt.minion      :1711][INFO    ][15110] Returning information for job: 20190513025636251407
2019-05-13 02:56:51,256 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.32484818s left)
2019-05-13 02:57:06,368 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025706357475
2019-05-13 02:57:06,389 [salt.minion      :1432][INFO    ][15187] Starting a new job with PID 15187
2019-05-13 02:57:06,411 [salt.minion      :1711][INFO    ][15187] Returning information for job: 20190513025706357475
2019-05-13 02:57:24,301 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.27929902s left)
2019-05-13 02:57:36,451 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025736438558
2019-05-13 02:57:36,477 [salt.minion      :1432][INFO    ][15240] Starting a new job with PID 15240
2019-05-13 02:57:36,502 [salt.minion      :1711][INFO    ][15240] Returning information for job: 20190513025736438558
2019-05-13 02:57:57,801 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1950.77982402s left)
2019-05-13 02:58:06,596 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025806579836
2019-05-13 02:58:06,628 [salt.minion      :1432][INFO    ][15355] Starting a new job with PID 15355
2019-05-13 02:58:06,655 [salt.minion      :1711][INFO    ][15355] Returning information for job: 20190513025806579836
2019-05-13 02:58:30,670 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1917.91005111s left)
2019-05-13 02:58:36,728 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025836712684
2019-05-13 02:58:36,755 [salt.minion      :1432][INFO    ][15405] Starting a new job with PID 15405
2019-05-13 02:58:36,778 [salt.minion      :1711][INFO    ][15405] Returning information for job: 20190513025836712684
2019-05-13 02:59:03,611 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1884.9692421s left)
2019-05-13 02:59:06,868 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025906851423
2019-05-13 02:59:06,897 [salt.minion      :1432][INFO    ][15498] Starting a new job with PID 15498
2019-05-13 02:59:06,920 [salt.minion      :1711][INFO    ][15498] Returning information for job: 20190513025906851423
2019-05-13 02:59:36,820 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1851.76054811s left)
2019-05-13 02:59:36,992 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513025936980661
2019-05-13 02:59:37,009 [salt.minion      :1432][INFO    ][15539] Starting a new job with PID 15539
2019-05-13 02:59:37,030 [salt.minion      :1711][INFO    ][15539] Returning information for job: 20190513025936980661
2019-05-13 03:00:07,111 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513030007096595
2019-05-13 03:00:07,133 [salt.minion      :1432][INFO    ][15644] Starting a new job with PID 15644
2019-05-13 03:00:07,157 [salt.minion      :1711][INFO    ][15644] Returning information for job: 20190513030007096595
2019-05-13 03:00:09,685 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1818.89585805s left)
2019-05-13 03:00:37,244 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513030037234985
2019-05-13 03:00:37,272 [salt.minion      :1432][INFO    ][15710] Starting a new job with PID 15710
2019-05-13 03:00:37,295 [salt.minion      :1711][INFO    ][15710] Returning information for job: 20190513030037234985
2019-05-13 03:00:42,575 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.00536108s left)
2019-05-13 03:01:07,412 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513030107395238
2019-05-13 03:01:07,443 [salt.minion      :1432][INFO    ][15836] Starting a new job with PID 15836
2019-05-13 03:01:07,473 [salt.minion      :1711][INFO    ][15836] Returning information for job: 20190513030107395238
2019-05-13 03:01:15,532 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.0487411s left)
2019-05-13 03:01:37,590 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513030137579110
2019-05-13 03:01:37,618 [salt.minion      :1432][INFO    ][15863] Starting a new job with PID 15863
2019-05-13 03:01:37,652 [salt.minion      :1711][INFO    ][15863] Returning information for job: 20190513030137579110
2019-05-13 03:01:48,442 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.13863015s left)
2019-05-13 03:02:07,747 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513030207735721
2019-05-13 03:02:07,774 [salt.minion      :1432][INFO    ][15915] Starting a new job with PID 15915
2019-05-13 03:02:07,813 [salt.minion      :1711][INFO    ][15915] Returning information for job: 20190513030207735721
2019-05-13 03:02:21,304 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.2767241s left)
2019-05-13 03:02:37,933 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513030237924922
2019-05-13 03:02:37,961 [salt.minion      :1432][INFO    ][15942] Starting a new job with PID 15942
2019-05-13 03:02:37,988 [salt.minion      :1711][INFO    ][15942] Returning information for job: 20190513030237924922
2019-05-13 03:02:54,221 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.35983205s left)
2019-05-13 03:03:08,088 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513030308078976
2019-05-13 03:03:08,113 [salt.minion      :1432][INFO    ][15993] Starting a new job with PID 15993
2019-05-13 03:03:08,137 [salt.minion      :1711][INFO    ][15993] Returning information for job: 20190513030308078976
2019-05-13 03:03:27,238 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.34271908s left)
2019-05-13 03:03:38,264 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513030338254187
2019-05-13 03:03:38,292 [salt.minion      :1432][INFO    ][16016] Starting a new job with PID 16016
2019-05-13 03:03:38,321 [salt.minion      :1711][INFO    ][16016] Returning information for job: 20190513030338254187
2019-05-13 03:03:59,988 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.59202409s left)
2019-05-13 03:04:08,443 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513030408432952
2019-05-13 03:04:08,472 [salt.minion      :1432][INFO    ][16074] Starting a new job with PID 16074
2019-05-13 03:04:08,495 [salt.minion      :1711][INFO    ][16074] Returning information for job: 20190513030408432952
2019-05-13 03:04:33,069 [salt.loaded.ext.module.maas:1023][INFO    ][14769] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.51135111s left)
2019-05-13 03:04:38,647 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command saltutil.find_job with jid 20190513030438631807
2019-05-13 03:04:38,674 [salt.minion      :1432][INFO    ][16118] Starting a new job with PID 16118
2019-05-13 03:04:38,701 [salt.minion      :1711][INFO    ][16118] Returning information for job: 20190513030438631807
2019-05-13 03:05:05,964 [salt.state       :300 ][INFO    ][14769] {'ret': True}
2019-05-13 03:05:05,965 [salt.state       :1951][INFO    ][14769] Completed state [maas.wait_for_machine_status] at time 03:05:05.965414 duration_in_ms=727408.27
2019-05-13 03:05:05,972 [salt.minion      :1711][INFO    ][14769] Returning information for job: 20190513025250566864
2019-05-13 03:06:00,291 [salt.utils.schedule:1377][INFO    ][6255] Running scheduled job: __mine_interval
2019-05-13 04:06:00,292 [salt.utils.schedule:1377][INFO    ][6255] Running scheduled job: __mine_interval
2019-05-13 04:31:14,657 [salt.minion      :1308][INFO    ][6255] User sudo_ubuntu Executing command cp.push_dir with jid 20190513043114646073
2019-05-13 04:31:14,686 [salt.minion      :1432][INFO    ][22435] Starting a new job with PID 22435
