2019-06-02 06:44:28,136 [salt.minion      :870 ][ERROR   ][382] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-02 06:45:44,542 [salt.utils.decorators:613 ][WARNING ][1965] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 06:45:45,633 [salt.utils.decorators:613 ][WARNING ][1965] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 06:45:48,744 [salt.loaded.int.states.file:2298][WARNING ][2423] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-02 06:46:19,531 [salt.state       :2022][WARNING ][2989] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-02 06:46:22,581 [salt.utils.decorators:613 ][WARNING ][2989] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 06:46:35,232 [salt.utils.decorators:613 ][WARNING ][2989] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 06:47:00,937 [salt.utils.decorators:613 ][WARNING ][2989] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 06:47:01,893 [salt.utils.decorators:613 ][WARNING ][2989] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 06:47:04,177 [salt.loaded.ext.module.maasng:1008][WARNING ][2989] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-02 06:47:04,177 [salt.loaded.ext.module.maasng:1011][WARNING ][2989] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-02 06:47:04,870 [salt.loaded.ext.module.maasng:1235][WARNING ][2989] Ignoring parameter vlan:0
2019-06-02 06:47:05,659 [salt.utils.decorators:613 ][WARNING ][2989] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 06:47:14,701 [salt.utils.decorators:613 ][WARNING ][5360] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 06:47:14,778 [salt.loaded.ext.module.maas:412 ][WARNING ][5360] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 06:47:16,315 [salt.loaded.ext.module.maas:412 ][WARNING ][5360] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 06:47:17,747 [salt.loaded.ext.module.maas:412 ][WARNING ][5360] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 06:47:19,016 [salt.loaded.ext.module.maas:412 ][WARNING ][5360] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 06:47:20,428 [salt.loaded.ext.module.maas:412 ][WARNING ][5360] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 06:47:26,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5977] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-02 06:47:26,276 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5977] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-02 06:47:26,315 [salt.utils.parsers:1051][WARNING ][382] Minion received a SIGTERM. Exiting.
2019-06-02 06:47:27,324 [salt.cli.daemons :293 ][INFO    ][6034] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-06-02 06:47:27,444 [salt.cli.daemons :82  ][INFO    ][6034] Starting up the Salt Minion
2019-06-02 06:47:27,445 [salt.utils.event :1017][INFO    ][6034] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-06-02 06:47:28,603 [salt.minion      :976 ][INFO    ][6034] Creating minion process manager
2019-06-02 06:47:30,542 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6034] Executing command ['date', '+%z'] in directory '/root'
2019-06-02 06:47:30,571 [salt.utils.schedule:568 ][INFO    ][6034] Updating job settings for scheduled job: __mine_interval
2019-06-02 06:47:30,573 [salt.minion      :1108][INFO    ][6034] Added mine.update to scheduler
2019-06-02 06:47:30,580 [salt.minion      :1975][INFO    ][6034] Minion is starting as user 'root'
2019-06-02 06:47:30,598 [salt.minion      :2336][INFO    ][6034] Minion is ready to receive requests!
2019-06-02 06:47:52,896 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602064752882417
2019-06-02 06:47:52,926 [salt.minion      :1432][INFO    ][6123] Starting a new job with PID 6123
2019-06-02 06:47:58,877 [salt.state       :915 ][INFO    ][6123] Loading fresh modules for state activity
2019-06-02 06:47:58,939 [salt.fileclient  :1219][INFO    ][6123] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-02 06:47:58,985 [salt.state       :1780][INFO    ][6123] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:47:58.985358
2019-06-02 06:47:58,985 [salt.state       :1813][INFO    ][6123] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 06:47:58,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6123] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 06:48:00,680 [salt.state       :300 ][INFO    ][6123] {'pid': 6131, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 06:48:00,681 [salt.state       :1951][INFO    ][6123] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:48:00.681362 duration_in_ms=1696.004
2019-06-02 06:48:00,686 [salt.state       :1780][INFO    ][6123] Running state [maas.wait_for_machine_status] at time 06:48:00.685654
2019-06-02 06:48:00,687 [salt.state       :1813][INFO    ][6123] Executing state module.run for [maas.wait_for_machine_status]
2019-06-02 06:48:00,688 [salt.utils.decorators:613 ][WARNING ][6123] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 06:48:01,613 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.08277011s left)
2019-06-02 06:48:07,955 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602064807936669
2019-06-02 06:48:07,986 [salt.minion      :1432][INFO    ][6142] Starting a new job with PID 6142
2019-06-02 06:48:08,008 [salt.minion      :1711][INFO    ][6142] Returning information for job: 20190602064807936669
2019-06-02 06:48:32,429 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.26671505s left)
2019-06-02 06:48:38,023 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602064838009287
2019-06-02 06:48:38,051 [salt.minion      :1432][INFO    ][6197] Starting a new job with PID 6197
2019-06-02 06:48:38,075 [salt.minion      :1711][INFO    ][6197] Returning information for job: 20190602064838009287
2019-06-02 06:49:03,244 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.45133615s left)
2019-06-02 06:49:08,109 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602064908093449
2019-06-02 06:49:08,135 [salt.minion      :1432][INFO    ][6216] Starting a new job with PID 6216
2019-06-02 06:49:08,159 [salt.minion      :1711][INFO    ][6216] Returning information for job: 20190602064908093449
2019-06-02 06:49:34,150 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.544945s left)
2019-06-02 06:49:38,193 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602064938181233
2019-06-02 06:49:38,215 [salt.minion      :1432][INFO    ][6274] Starting a new job with PID 6274
2019-06-02 06:49:38,235 [salt.minion      :1711][INFO    ][6274] Returning information for job: 20190602064938181233
2019-06-02 06:50:05,248 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.446908s left)
2019-06-02 06:50:08,251 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065008234729
2019-06-02 06:50:08,278 [salt.minion      :1432][INFO    ][6326] Starting a new job with PID 6326
2019-06-02 06:50:08,297 [salt.minion      :1711][INFO    ][6326] Returning information for job: 20190602065008234729
2019-06-02 06:50:36,357 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.33858514s left)
2019-06-02 06:50:38,332 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065038312002
2019-06-02 06:50:38,355 [salt.minion      :1432][INFO    ][6487] Starting a new job with PID 6487
2019-06-02 06:50:38,375 [salt.minion      :1711][INFO    ][6487] Returning information for job: 20190602065038312002
2019-06-02 06:51:07,523 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.17239404s left)
2019-06-02 06:51:08,434 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065108415612
2019-06-02 06:51:08,463 [salt.minion      :1432][INFO    ][6555] Starting a new job with PID 6555
2019-06-02 06:51:08,488 [salt.minion      :1711][INFO    ][6555] Returning information for job: 20190602065108415612
2019-06-02 06:51:38,575 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065138566445
2019-06-02 06:51:38,592 [salt.minion      :1432][INFO    ][6878] Starting a new job with PID 6878
2019-06-02 06:51:38,614 [salt.minion      :1711][INFO    ][6878] Returning information for job: 20190602065138566445
2019-06-02 06:51:38,889 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.80625701s left)
2019-06-02 06:52:08,652 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065208637006
2019-06-02 06:52:08,677 [salt.minion      :1432][INFO    ][6988] Starting a new job with PID 6988
2019-06-02 06:52:08,705 [salt.minion      :1711][INFO    ][6988] Returning information for job: 20190602065208637006
2019-06-02 06:52:10,858 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.837075s left)
2019-06-02 06:52:38,802 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065238789263
2019-06-02 06:52:38,831 [salt.minion      :1432][INFO    ][7343] Starting a new job with PID 7343
2019-06-02 06:52:38,856 [salt.minion      :1711][INFO    ][7343] Returning information for job: 20190602065238789263
2019-06-02 06:52:43,039 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.655931s left)
2019-06-02 06:53:08,933 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065308918856
2019-06-02 06:53:08,957 [salt.minion      :1432][INFO    ][7494] Starting a new job with PID 7494
2019-06-02 06:53:08,976 [salt.minion      :1711][INFO    ][7494] Returning information for job: 20190602065308918856
2019-06-02 06:53:15,282 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.41355205s left)
2019-06-02 06:53:39,069 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065339058955
2019-06-02 06:53:39,097 [salt.minion      :1432][INFO    ][7702] Starting a new job with PID 7702
2019-06-02 06:53:39,119 [salt.minion      :1711][INFO    ][7702] Returning information for job: 20190602065339058955
2019-06-02 06:53:47,505 [salt.loaded.ext.module.maas:1023][INFO    ][6123] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.190202s left)
2019-06-02 06:54:09,211 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065409199387
2019-06-02 06:54:09,250 [salt.minion      :1432][INFO    ][7860] Starting a new job with PID 7860
2019-06-02 06:54:09,286 [salt.minion      :1711][INFO    ][7860] Returning information for job: 20190602065409199387
2019-06-02 06:54:20,406 [salt.state       :300 ][INFO    ][6123] {'ret': True}
2019-06-02 06:54:20,407 [salt.state       :1951][INFO    ][6123] Completed state [maas.wait_for_machine_status] at time 06:54:20.407317 duration_in_ms=379721.661
2019-06-02 06:54:20,411 [salt.minion      :1711][INFO    ][6123] Returning information for job: 20190602064752882417
2019-06-02 06:54:21,009 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602065420995213
2019-06-02 06:54:21,036 [salt.minion      :1432][INFO    ][7901] Starting a new job with PID 7901
2019-06-02 06:54:27,112 [salt.state       :915 ][INFO    ][7901] Loading fresh modules for state activity
2019-06-02 06:54:27,174 [salt.fileclient  :1219][INFO    ][7901] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-02 06:54:27,273 [salt.state       :1780][INFO    ][7901] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:54:27.273344
2019-06-02 06:54:27,273 [salt.state       :1813][INFO    ][7901] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 06:54:27,275 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7901] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 06:54:28,993 [salt.state       :300 ][INFO    ][7901] {'pid': 7978, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 06:54:28,995 [salt.state       :1951][INFO    ][7901] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:54:28.995351 duration_in_ms=1722.006
2019-06-02 06:54:28,999 [salt.state       :1780][INFO    ][7901] Running state [maas_machines_storage_cmp002_lvm] at time 06:54:28.999233
2019-06-02 06:54:28,999 [salt.state       :1813][INFO    ][7901] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-02 06:54:30,292 [salt.loaded.ext.module.maasng:610 ][INFO    ][7901] xrkf7b
2019-06-02 06:54:30,292 [salt.loaded.ext.module.maasng:626 ][INFO    ][7901] sda
2019-06-02 06:54:30,868 [salt.loaded.ext.module.maasng:361 ][INFO    ][7901] xrkf7b
2019-06-02 06:54:30,960 [salt.loaded.ext.module.maasng:367 ][INFO    ][7901] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'7f1c620f-4ef4-4b63-a95d-f5b6bd9ef6e3', u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xrkf7b', u'filesystem': {u'uuid': u'3822f16d-35e6-45c1-b169-94f4a35a251c', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xrkf7b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/blockdevices/3/', u'available_size': 0, u'uuid': u'eccc922a-8ecf-41bc-96ab-5707944f7885', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xrkf7b', u'partition_table_type': None, u'filesystem': {u'uuid': u'499b5d2f-9105-4bfc-b3db-f0985cff7354', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'size': 800097042432}]
2019-06-02 06:54:30,960 [salt.loaded.ext.module.maasng:632 ][INFO    ][7901] vgroot
2019-06-02 06:54:30,960 [salt.loaded.ext.module.maasng:635 ][INFO    ][7901] lvroot
2019-06-02 06:54:30,961 [salt.loaded.ext.module.maasng:639 ][INFO    ][7901] 107374182400
2019-06-02 06:54:31,551 [salt.loaded.ext.module.maasng:645 ][INFO    ][7901] {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'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'da24b972-1002-4fca-a0dd-438c4f101918', u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xrkf7b', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'607c1e72-30bc-47db-aef5-9691111c345b', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xrkf7b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'xrkf7b', 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'disable_ipv4': False, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'da24b972-1002-4fca-a0dd-438c4f101918', u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xrkf7b', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'607c1e72-30bc-47db-aef5-9691111c345b', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xrkf7b', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/blockdevices/11/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'f69dc401-808a-44f7-90ab-5891380e4645', u'id': 11, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'xrkf7b', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'f9c85dc8-dc77-44be-8a4b-fe2ab5b4c54b', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'da24b972-1002-4fca-a0dd-438c4f101918', u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xrkf7b', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'607c1e72-30bc-47db-aef5-9691111c345b', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xrkf7b', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xrkf7b', 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'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/blockdevices/11/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'xrkf7b', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'f9c85dc8-dc77-44be-8a4b-fe2ab5b4c54b', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'serial': None, u'uuid': u'f69dc401-808a-44f7-90ab-5891380e4645'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'm8xw38', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'm8xw38', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'm8xw38', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xrkf7b', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/interfaces/4/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'm8xw38', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'm8xw38', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'm8xw38', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xrkf7b', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xrkf7b', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/interfaces/15/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xrkf7b', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/interfaces/12/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xrkf7b', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xrkf7b', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/interfaces/11/'}, {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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'm8xw38', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'm8xw38', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'm8xw38', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xrkf7b', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/xrkf7b/interfaces/13/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/xrkf7b/', u'distro_series': u'', u'memory_test_status': -1}
2019-06-02 06:54:31,554 [salt.state       :300 ][INFO    ][7901] {'new': {'storage_layout': 'lvm'}}
2019-06-02 06:54:31,555 [salt.state       :1951][INFO    ][7901] Completed state [maas_machines_storage_cmp002_lvm] at time 06:54:31.555146 duration_in_ms=2555.911
2019-06-02 06:54:31,555 [salt.state       :1780][INFO    ][7901] Running state [maas_machines_storage_cmp001_lvm] at time 06:54:31.555665
2019-06-02 06:54:31,556 [salt.state       :1813][INFO    ][7901] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-02 06:54:32,724 [salt.loaded.ext.module.maasng:610 ][INFO    ][7901] dsn4np
2019-06-02 06:54:32,725 [salt.loaded.ext.module.maasng:626 ][INFO    ][7901] sda
2019-06-02 06:54:33,305 [salt.loaded.ext.module.maasng:361 ][INFO    ][7901] dsn4np
2019-06-02 06:54:33,397 [salt.loaded.ext.module.maasng:367 ][INFO    ][7901] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'352af9c6-e7cc-4399-bca2-80b4681096fa', u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dsn4np', u'filesystem': {u'uuid': u'c12609e8-1884-4c69-a441-1916c8565f51', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 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'system_id': u'dsn4np', 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'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'776bb629-c45a-4e2a-835a-f7fea382ec35', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'211eec96-194e-4cd4-927f-5b89248fba1b', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dsn4np', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/blockdevices/4/', u'used_size': 800097042432, u'id': 4, u'serial': None, u'size': 800097042432}]
2019-06-02 06:54:33,399 [salt.loaded.ext.module.maasng:632 ][INFO    ][7901] vgroot
2019-06-02 06:54:33,399 [salt.loaded.ext.module.maasng:635 ][INFO    ][7901] lvroot
2019-06-02 06:54:33,399 [salt.loaded.ext.module.maasng:639 ][INFO    ][7901] 107374182400
2019-06-02 06:54:34,012 [salt.loaded.ext.module.maasng:645 ][INFO    ][7901] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'memory_test_status_name': u'Unknown', 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'm8xw38', 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'm8xw38', 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.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'm8xw38', 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.39'}], u'system_id': u'dsn4np', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'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'dsn4np', 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'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'c1f9fe86-6561-4394-9807-21483b73e359', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dsn4np', u'device_id': 2, u'filesystem': {u'uuid': u'6199a4a6-4f12-433d-baa7-18b94bd3b5d2', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'dsn4np', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, 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'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'uuid': u'a81097c1-69c6-4687-8140-2e4286788ff8', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'7ee37b13-e2fc-4f03-96ae-05d723fd5e66', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dsn4np', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dsn4np', 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'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'c1f9fe86-6561-4394-9807-21483b73e359', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dsn4np', u'device_id': 2, u'filesystem': {u'uuid': u'6199a4a6-4f12-433d-baa7-18b94bd3b5d2', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/blockdevices/12/', u'used_size': 107374182400, u'tags': [], u'filesystem': {u'uuid': u'a81097c1-69c6-4687-8140-2e4286788ff8', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'7ee37b13-e2fc-4f03-96ae-05d723fd5e66', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dsn4np', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, 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'dsn4np', 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'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'c1f9fe86-6561-4394-9807-21483b73e359', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dsn4np', u'device_id': 2, u'filesystem': {u'uuid': u'6199a4a6-4f12-433d-baa7-18b94bd3b5d2', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'dsn4np', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'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'm8xw38', 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'm8xw38', 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.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'm8xw38', 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.39'}], u'system_id': u'dsn4np', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'system_id': u'dsn4np', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/interfaces/17/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'system_id': u'dsn4np', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/interfaces/20/'}, {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': 26}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'system_id': u'dsn4np', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/interfaces/16/'}, {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'm8xw38', 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'm8xw38', 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': 27}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'effective_mtu': 1500, 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'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'm8xw38', 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.44'}], u'system_id': u'dsn4np', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/interfaces/18/'}, {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': 28}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'system_id': u'dsn4np', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dsn4np/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/dsn4np/'}
2019-06-02 06:54:34,014 [salt.state       :300 ][INFO    ][7901] {'new': {'storage_layout': 'lvm'}}
2019-06-02 06:54:34,015 [salt.state       :1951][INFO    ][7901] Completed state [maas_machines_storage_cmp001_lvm] at time 06:54:34.015193 duration_in_ms=2459.526
2019-06-02 06:54:34,019 [salt.minion      :1711][INFO    ][7901] Returning information for job: 20190602065420995213
2019-06-02 06:54:34,582 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602065434567120
2019-06-02 06:54:34,611 [salt.minion      :1432][INFO    ][7997] Starting a new job with PID 7997
2019-06-02 06:54:35,560 [salt.state       :915 ][INFO    ][7997] Loading fresh modules for state activity
2019-06-02 06:54:35,613 [salt.fileclient  :1219][INFO    ][7997] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-02 06:54:35,660 [salt.state       :1780][INFO    ][7997] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:54:35.660236
2019-06-02 06:54:35,660 [salt.state       :1813][INFO    ][7997] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 06:54:35,662 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7997] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 06:54:37,432 [salt.state       :300 ][INFO    ][7997] {'pid': 8004, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 06:54:37,433 [salt.state       :1951][INFO    ][7997] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:54:37.433146 duration_in_ms=1772.908
2019-06-02 06:54:37,436 [salt.state       :1780][INFO    ][7997] Running state [maas.deploy_machines] at time 06:54:37.436398
2019-06-02 06:54:37,437 [salt.state       :1813][INFO    ][7997] Executing state module.run for [maas.deploy_machines]
2019-06-02 06:54:37,438 [salt.utils.decorators:613 ][WARNING ][7997] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 06:54:38,007 [salt.loaded.ext.module.maas:684 ][INFO    ][7997] deploymachines hwe_kernel=hwe-16.04 system_id=xrkf7b distro_series=xenial
2019-06-02 06:54:40,628 [salt.loaded.ext.module.maas:684 ][INFO    ][7997] deploymachines hwe_kernel=hwe-16.04 system_id=dsn4np distro_series=xenial
2019-06-02 06:54:43,141 [salt.loaded.ext.module.maas:684 ][INFO    ][7997] deploymachines hwe_kernel=hwe-16.04 system_id=a67pgw distro_series=xenial
2019-06-02 06:54:45,658 [salt.loaded.ext.module.maas:684 ][INFO    ][7997] deploymachines hwe_kernel=hwe-16.04 system_id=b4rwmb distro_series=xenial
2019-06-02 06:54:48,209 [salt.loaded.ext.module.maas:684 ][INFO    ][7997] deploymachines hwe_kernel=hwe-16.04 system_id=pa4ar3 distro_series=xenial
2019-06-02 06:54:49,631 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065449623142
2019-06-02 06:54:49,650 [salt.minion      :1432][INFO    ][8265] Starting a new job with PID 8265
2019-06-02 06:54:49,674 [salt.minion      :1711][INFO    ][8265] Returning information for job: 20190602065449623142
2019-06-02 06:54:50,700 [salt.state       :300 ][INFO    ][7997] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-02 06:54:50,700 [salt.state       :1951][INFO    ][7997] Completed state [maas.deploy_machines] at time 06:54:50.700406 duration_in_ms=13264.008
2019-06-02 06:54:50,706 [salt.minion      :1711][INFO    ][7997] Returning information for job: 20190602065434567120
2019-06-02 06:54:51,258 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602065451248697
2019-06-02 06:54:51,285 [salt.minion      :1432][INFO    ][8281] Starting a new job with PID 8281
2019-06-02 06:54:57,231 [salt.state       :915 ][INFO    ][8281] Loading fresh modules for state activity
2019-06-02 06:54:57,294 [salt.fileclient  :1219][INFO    ][8281] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-02 06:54:57,341 [salt.state       :1780][INFO    ][8281] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:54:57.341167
2019-06-02 06:54:57,341 [salt.state       :1813][INFO    ][8281] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 06:54:57,343 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8281] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 06:54:59,047 [salt.state       :300 ][INFO    ][8281] {'pid': 8298, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 06:54:59,048 [salt.state       :1951][INFO    ][8281] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:54:59.048347 duration_in_ms=1707.178
2019-06-02 06:54:59,052 [salt.state       :1780][INFO    ][8281] Running state [maas.wait_for_machine_status] at time 06:54:59.052158
2019-06-02 06:54:59,052 [salt.state       :1813][INFO    ][8281] Executing state module.run for [maas.wait_for_machine_status]
2019-06-02 06:54:59,054 [salt.utils.decorators:613 ][WARNING ][8281] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 06:55:02,080 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.98245001s left)
2019-06-02 06:55:06,305 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065506290814
2019-06-02 06:55:06,330 [salt.minion      :1432][INFO    ][8309] Starting a new job with PID 8309
2019-06-02 06:55:06,352 [salt.minion      :1711][INFO    ][8309] Returning information for job: 20190602065506290814
2019-06-02 06:55:34,929 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.13365388s left)
2019-06-02 06:55:36,381 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065536370788
2019-06-02 06:55:36,405 [salt.minion      :1432][INFO    ][8366] Starting a new job with PID 8366
2019-06-02 06:55:36,426 [salt.minion      :1711][INFO    ][8366] Returning information for job: 20190602065536370788
2019-06-02 06:56:06,456 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065606442727
2019-06-02 06:56:06,474 [salt.minion      :1432][INFO    ][8385] Starting a new job with PID 8385
2019-06-02 06:56:06,497 [salt.minion      :1711][INFO    ][8385] Returning information for job: 20190602065606442727
2019-06-02 06:56:07,938 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.12467885s left)
2019-06-02 06:56:36,532 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065636515993
2019-06-02 06:56:36,556 [salt.minion      :1432][INFO    ][8465] Starting a new job with PID 8465
2019-06-02 06:56:36,580 [salt.minion      :1711][INFO    ][8465] Returning information for job: 20190602065636515993
2019-06-02 06:56:41,049 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.01396298s left)
2019-06-02 06:57:06,623 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065706607107
2019-06-02 06:57:06,652 [salt.minion      :1432][INFO    ][8494] Starting a new job with PID 8494
2019-06-02 06:57:06,673 [salt.minion      :1711][INFO    ][8494] Returning information for job: 20190602065706607107
2019-06-02 06:57:13,823 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.24018693s left)
2019-06-02 06:57:36,707 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065736688915
2019-06-02 06:57:36,738 [salt.minion      :1432][INFO    ][8572] Starting a new job with PID 8572
2019-06-02 06:57:36,759 [salt.minion      :1711][INFO    ][8572] Returning information for job: 20190602065736688915
2019-06-02 06:57:46,654 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.40887594s left)
2019-06-02 06:58:06,815 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065806798274
2019-06-02 06:58:06,840 [salt.minion      :1432][INFO    ][8646] Starting a new job with PID 8646
2019-06-02 06:58:06,864 [salt.minion      :1711][INFO    ][8646] Returning information for job: 20190602065806798274
2019-06-02 06:58:19,637 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.42625785s left)
2019-06-02 06:58:36,937 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065836926450
2019-06-02 06:58:36,967 [salt.minion      :1432][INFO    ][8930] Starting a new job with PID 8930
2019-06-02 06:58:36,987 [salt.minion      :1711][INFO    ][8930] Returning information for job: 20190602065836926450
2019-06-02 06:58:52,497 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.56579995s left)
2019-06-02 06:59:07,008 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065906995290
2019-06-02 06:59:07,031 [salt.minion      :1432][INFO    ][8990] Starting a new job with PID 8990
2019-06-02 06:59:07,050 [salt.minion      :1711][INFO    ][8990] Returning information for job: 20190602065906995290
2019-06-02 06:59:26,444 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.6184659s left)
2019-06-02 06:59:37,105 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602065937097156
2019-06-02 06:59:37,131 [salt.minion      :1432][INFO    ][9188] Starting a new job with PID 9188
2019-06-02 06:59:37,153 [salt.minion      :1711][INFO    ][9188] Returning information for job: 20190602065937097156
2019-06-02 06:59:59,316 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.74639487s left)
2019-06-02 07:00:07,231 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070007212817
2019-06-02 07:00:07,260 [salt.minion      :1432][INFO    ][9237] Starting a new job with PID 9237
2019-06-02 07:00:07,284 [salt.minion      :1711][INFO    ][9237] Returning information for job: 20190602070007212817
2019-06-02 07:00:32,380 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.682266s left)
2019-06-02 07:00:37,411 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070037399076
2019-06-02 07:00:37,434 [salt.minion      :1432][INFO    ][9690] Starting a new job with PID 9690
2019-06-02 07:00:37,456 [salt.minion      :1711][INFO    ][9690] Returning information for job: 20190602070037399076
2019-06-02 07:01:05,287 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.77623296s left)
2019-06-02 07:01:07,544 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070107530336
2019-06-02 07:01:07,577 [salt.minion      :1432][INFO    ][9732] Starting a new job with PID 9732
2019-06-02 07:01:07,601 [salt.minion      :1711][INFO    ][9732] Returning information for job: 20190602070107530336
2019-06-02 07:01:37,760 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070137743242
2019-06-02 07:01:37,786 [salt.minion      :1432][INFO    ][10087] Starting a new job with PID 10087
2019-06-02 07:01:37,810 [salt.minion      :1711][INFO    ][10087] Returning information for job: 20190602070137743242
2019-06-02 07:01:38,397 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.66599083s left)
2019-06-02 07:02:07,903 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070207889323
2019-06-02 07:02:07,931 [salt.minion      :1432][INFO    ][10119] Starting a new job with PID 10119
2019-06-02 07:02:07,949 [salt.minion      :1711][INFO    ][10119] Returning information for job: 20190602070207889323
2019-06-02 07:02:11,407 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.65539384s left)
2019-06-02 07:02:38,086 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070238076137
2019-06-02 07:02:38,118 [salt.minion      :1432][INFO    ][10424] Starting a new job with PID 10424
2019-06-02 07:02:38,138 [salt.minion      :1711][INFO    ][10424] Returning information for job: 20190602070238076137
2019-06-02 07:02:44,405 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.65810704s left)
2019-06-02 07:03:08,240 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070308223189
2019-06-02 07:03:08,269 [salt.minion      :1432][INFO    ][10468] Starting a new job with PID 10468
2019-06-02 07:03:08,289 [salt.minion      :1711][INFO    ][10468] Returning information for job: 20190602070308223189
2019-06-02 07:03:17,269 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.79391885s left)
2019-06-02 07:03:38,459 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070338447832
2019-06-02 07:03:38,485 [salt.minion      :1432][INFO    ][10760] Starting a new job with PID 10760
2019-06-02 07:03:38,512 [salt.minion      :1711][INFO    ][10760] Returning information for job: 20190602070338447832
2019-06-02 07:03:50,161 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.90158486s left)
2019-06-02 07:04:08,674 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070408666537
2019-06-02 07:04:08,698 [salt.minion      :1432][INFO    ][10794] Starting a new job with PID 10794
2019-06-02 07:04:08,718 [salt.minion      :1711][INFO    ][10794] Returning information for job: 20190602070408666537
2019-06-02 07:04:23,194 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.86899495s left)
2019-06-02 07:04:38,864 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070438847396
2019-06-02 07:04:38,892 [salt.minion      :1432][INFO    ][10971] Starting a new job with PID 10971
2019-06-02 07:04:38,914 [salt.minion      :1711][INFO    ][10971] Returning information for job: 20190602070438847396
2019-06-02 07:04:56,177 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.88569403s left)
2019-06-02 07:05:08,897 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070508878868
2019-06-02 07:05:08,927 [salt.minion      :1432][INFO    ][11022] Starting a new job with PID 11022
2019-06-02 07:05:08,955 [salt.minion      :1711][INFO    ][11022] Returning information for job: 20190602070508878868
2019-06-02 07:05:29,034 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1620.02840304s left)
2019-06-02 07:05:39,108 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070539091537
2019-06-02 07:05:39,138 [salt.minion      :1432][INFO    ][11163] Starting a new job with PID 11163
2019-06-02 07:05:39,166 [salt.minion      :1711][INFO    ][11163] Returning information for job: 20190602070539091537
2019-06-02 07:06:01,991 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1587.0721519s left)
2019-06-02 07:06:09,301 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070609286924
2019-06-02 07:06:09,335 [salt.minion      :1432][INFO    ][11200] Starting a new job with PID 11200
2019-06-02 07:06:09,369 [salt.minion      :1711][INFO    ][11200] Returning information for job: 20190602070609286924
2019-06-02 07:06:35,033 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1554.02989697s left)
2019-06-02 07:06:39,527 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070639512255
2019-06-02 07:06:39,553 [salt.minion      :1432][INFO    ][11388] Starting a new job with PID 11388
2019-06-02 07:06:39,586 [salt.minion      :1711][INFO    ][11388] Returning information for job: 20190602070639512255
2019-06-02 07:07:08,028 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1521.03461003s left)
2019-06-02 07:07:09,742 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070709731836
2019-06-02 07:07:09,764 [salt.minion      :1432][INFO    ][11438] Starting a new job with PID 11438
2019-06-02 07:07:09,788 [salt.minion      :1711][INFO    ][11438] Returning information for job: 20190602070709731836
2019-06-02 07:07:39,791 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070739780894
2019-06-02 07:07:39,815 [salt.minion      :1432][INFO    ][11566] Starting a new job with PID 11566
2019-06-02 07:07:39,835 [salt.minion      :1711][INFO    ][11566] Returning information for job: 20190602070739780894
2019-06-02 07:07:40,867 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1488.19620085s left)
2019-06-02 07:08:09,972 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070809963988
2019-06-02 07:08:09,995 [salt.minion      :1432][INFO    ][11621] Starting a new job with PID 11621
2019-06-02 07:08:10,015 [salt.minion      :1711][INFO    ][11621] Returning information for job: 20190602070809963988
2019-06-02 07:08:13,711 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1455.35185194s left)
2019-06-02 07:08:40,038 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070840025690
2019-06-02 07:08:40,066 [salt.minion      :1432][INFO    ][11860] Starting a new job with PID 11860
2019-06-02 07:08:40,088 [salt.minion      :1711][INFO    ][11860] Returning information for job: 20190602070840025690
2019-06-02 07:08:46,645 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1422.41763783s left)
2019-06-02 07:09:10,064 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070910049634
2019-06-02 07:09:10,092 [salt.minion      :1432][INFO    ][11889] Starting a new job with PID 11889
2019-06-02 07:09:10,117 [salt.minion      :1711][INFO    ][11889] Returning information for job: 20190602070910049634
2019-06-02 07:09:19,641 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1389.42209983s left)
2019-06-02 07:09:40,111 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602070940097076
2019-06-02 07:09:40,139 [salt.minion      :1432][INFO    ][11937] Starting a new job with PID 11937
2019-06-02 07:09:40,161 [salt.minion      :1711][INFO    ][11937] Returning information for job: 20190602070940097076
2019-06-02 07:09:52,621 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1356.44213986s left)
2019-06-02 07:10:10,175 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071010164359
2019-06-02 07:10:10,199 [salt.minion      :1432][INFO    ][11966] Starting a new job with PID 11966
2019-06-02 07:10:10,222 [salt.minion      :1711][INFO    ][11966] Returning information for job: 20190602071010164359
2019-06-02 07:10:25,775 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1323.28734493s left)
2019-06-02 07:10:40,231 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071040215149
2019-06-02 07:10:40,258 [salt.minion      :1432][INFO    ][12014] Starting a new job with PID 12014
2019-06-02 07:10:40,278 [salt.minion      :1711][INFO    ][12014] Returning information for job: 20190602071040215149
2019-06-02 07:10:58,578 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1290.48443294s left)
2019-06-02 07:11:10,325 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071110308663
2019-06-02 07:11:10,351 [salt.minion      :1432][INFO    ][12048] Starting a new job with PID 12048
2019-06-02 07:11:10,374 [salt.minion      :1711][INFO    ][12048] Returning information for job: 20190602071110308663
2019-06-02 07:11:31,651 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1257.41142988s left)
2019-06-02 07:11:40,395 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071140380010
2019-06-02 07:11:40,421 [salt.minion      :1432][INFO    ][12100] Starting a new job with PID 12100
2019-06-02 07:11:40,446 [salt.minion      :1711][INFO    ][12100] Returning information for job: 20190602071140380010
2019-06-02 07:12:04,400 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1224.66324687s left)
2019-06-02 07:12:10,488 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071210473952
2019-06-02 07:12:10,511 [salt.minion      :1432][INFO    ][12138] Starting a new job with PID 12138
2019-06-02 07:12:10,532 [salt.minion      :1711][INFO    ][12138] Returning information for job: 20190602071210473952
2019-06-02 07:12:37,241 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1191.82188392s left)
2019-06-02 07:12:40,583 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071240575990
2019-06-02 07:12:40,606 [salt.minion      :1432][INFO    ][12184] Starting a new job with PID 12184
2019-06-02 07:12:40,626 [salt.minion      :1711][INFO    ][12184] Returning information for job: 20190602071240575990
2019-06-02 07:13:10,048 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1159.01515388s left)
2019-06-02 07:13:10,727 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071310715571
2019-06-02 07:13:10,751 [salt.minion      :1432][INFO    ][12213] Starting a new job with PID 12213
2019-06-02 07:13:10,782 [salt.minion      :1711][INFO    ][12213] Returning information for job: 20190602071310715571
2019-06-02 07:13:40,885 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071340872471
2019-06-02 07:13:40,908 [salt.minion      :1432][INFO    ][12262] Starting a new job with PID 12262
2019-06-02 07:13:40,933 [salt.minion      :1711][INFO    ][12262] Returning information for job: 20190602071340872471
2019-06-02 07:13:42,911 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1126.15134788s left)
2019-06-02 07:14:11,032 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071411016584
2019-06-02 07:14:11,058 [salt.minion      :1432][INFO    ][12288] Starting a new job with PID 12288
2019-06-02 07:14:11,081 [salt.minion      :1711][INFO    ][12288] Returning information for job: 20190602071411016584
2019-06-02 07:14:15,783 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1093.27924991s left)
2019-06-02 07:14:41,224 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071441206764
2019-06-02 07:14:41,249 [salt.minion      :1432][INFO    ][12338] Starting a new job with PID 12338
2019-06-02 07:14:41,272 [salt.minion      :1711][INFO    ][12338] Returning information for job: 20190602071441206764
2019-06-02 07:14:48,502 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1060.56090784s left)
2019-06-02 07:15:11,392 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071511376588
2019-06-02 07:15:11,421 [salt.minion      :1432][INFO    ][12369] Starting a new job with PID 12369
2019-06-02 07:15:11,448 [salt.minion      :1711][INFO    ][12369] Returning information for job: 20190602071511376588
2019-06-02 07:15:21,214 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1027.84921193s left)
2019-06-02 07:15:41,607 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071541593207
2019-06-02 07:15:41,636 [salt.minion      :1432][INFO    ][12414] Starting a new job with PID 12414
2019-06-02 07:15:41,660 [salt.minion      :1711][INFO    ][12414] Returning information for job: 20190602071541593207
2019-06-02 07:15:54,163 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (994.900128841s left)
2019-06-02 07:16:11,819 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071611805586
2019-06-02 07:16:11,847 [salt.minion      :1432][INFO    ][12455] Starting a new job with PID 12455
2019-06-02 07:16:11,866 [salt.minion      :1711][INFO    ][12455] Returning information for job: 20190602071611805586
2019-06-02 07:16:27,022 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (962.040632963s left)
2019-06-02 07:16:41,852 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071641839698
2019-06-02 07:16:41,878 [salt.minion      :1432][INFO    ][12518] Starting a new job with PID 12518
2019-06-02 07:16:41,902 [salt.minion      :1711][INFO    ][12518] Returning information for job: 20190602071641839698
2019-06-02 07:16:59,700 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (929.362750053s left)
2019-06-02 07:17:11,876 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071711860514
2019-06-02 07:17:11,904 [salt.minion      :1432][INFO    ][12550] Starting a new job with PID 12550
2019-06-02 07:17:11,927 [salt.minion      :1711][INFO    ][12550] Returning information for job: 20190602071711860514
2019-06-02 07:17:32,572 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (896.490629911s left)
2019-06-02 07:17:41,949 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071741933051
2019-06-02 07:17:41,975 [salt.minion      :1432][INFO    ][12598] Starting a new job with PID 12598
2019-06-02 07:17:41,996 [salt.minion      :1711][INFO    ][12598] Returning information for job: 20190602071741933051
2019-06-02 07:18:05,359 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (863.703737974s left)
2019-06-02 07:18:11,983 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071811970958
2019-06-02 07:18:12,011 [salt.minion      :1432][INFO    ][12632] Starting a new job with PID 12632
2019-06-02 07:18:12,033 [salt.minion      :1711][INFO    ][12632] Returning information for job: 20190602071811970958
2019-06-02 07:18:38,225 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (830.838392019s left)
2019-06-02 07:18:42,068 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071842056251
2019-06-02 07:18:42,097 [salt.minion      :1432][INFO    ][12679] Starting a new job with PID 12679
2019-06-02 07:18:42,120 [salt.minion      :1711][INFO    ][12679] Returning information for job: 20190602071842056251
2019-06-02 07:19:10,983 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (798.080173969s left)
2019-06-02 07:19:12,169 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071912160144
2019-06-02 07:19:12,198 [salt.minion      :1432][INFO    ][12706] Starting a new job with PID 12706
2019-06-02 07:19:12,221 [salt.minion      :1711][INFO    ][12706] Returning information for job: 20190602071912160144
2019-06-02 07:19:42,292 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602071942282936
2019-06-02 07:19:42,317 [salt.minion      :1432][INFO    ][12751] Starting a new job with PID 12751
2019-06-02 07:19:42,339 [salt.minion      :1711][INFO    ][12751] Returning information for job: 20190602071942282936
2019-06-02 07:19:43,892 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (765.171121836s left)
2019-06-02 07:20:12,432 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072012418401
2019-06-02 07:20:12,457 [salt.minion      :1432][INFO    ][12778] Starting a new job with PID 12778
2019-06-02 07:20:12,480 [salt.minion      :1711][INFO    ][12778] Returning information for job: 20190602072012418401
2019-06-02 07:20:16,635 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (732.427458048s left)
2019-06-02 07:20:42,547 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072042533481
2019-06-02 07:20:42,580 [salt.minion      :1432][INFO    ][12826] Starting a new job with PID 12826
2019-06-02 07:20:42,605 [salt.minion      :1711][INFO    ][12826] Returning information for job: 20190602072042533481
2019-06-02 07:20:49,484 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (699.579013824s left)
2019-06-02 07:21:12,739 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072112728323
2019-06-02 07:21:12,766 [salt.minion      :1432][INFO    ][12855] Starting a new job with PID 12855
2019-06-02 07:21:12,789 [salt.minion      :1711][INFO    ][12855] Returning information for job: 20190602072112728323
2019-06-02 07:21:22,313 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (666.749686956s left)
2019-06-02 07:21:42,953 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072142937618
2019-06-02 07:21:42,980 [salt.minion      :1432][INFO    ][12900] Starting a new job with PID 12900
2019-06-02 07:21:43,004 [salt.minion      :1711][INFO    ][12900] Returning information for job: 20190602072142937618
2019-06-02 07:21:55,073 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (633.989907026s left)
2019-06-02 07:22:12,985 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072212971996
2019-06-02 07:22:13,008 [salt.minion      :1432][INFO    ][12930] Starting a new job with PID 12930
2019-06-02 07:22:13,037 [salt.minion      :1711][INFO    ][12930] Returning information for job: 20190602072212971996
2019-06-02 07:22:27,862 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (601.201457024s left)
2019-06-02 07:22:43,044 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072243030435
2019-06-02 07:22:43,069 [salt.minion      :1432][INFO    ][12978] Starting a new job with PID 12978
2019-06-02 07:22:43,091 [salt.minion      :1711][INFO    ][12978] Returning information for job: 20190602072243030435
2019-06-02 07:23:00,639 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (568.423472881s left)
2019-06-02 07:23:13,140 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072313124410
2019-06-02 07:23:13,169 [salt.minion      :1432][INFO    ][13006] Starting a new job with PID 13006
2019-06-02 07:23:13,196 [salt.minion      :1711][INFO    ][13006] Returning information for job: 20190602072313124410
2019-06-02 07:23:33,427 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (535.63529706s left)
2019-06-02 07:23:43,172 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072343160667
2019-06-02 07:23:43,196 [salt.minion      :1432][INFO    ][13051] Starting a new job with PID 13051
2019-06-02 07:23:43,220 [salt.minion      :1711][INFO    ][13051] Returning information for job: 20190602072343160667
2019-06-02 07:24:06,249 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (502.813303947s left)
2019-06-02 07:24:13,303 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072413292760
2019-06-02 07:24:13,331 [salt.minion      :1432][INFO    ][13079] Starting a new job with PID 13079
2019-06-02 07:24:13,358 [salt.minion      :1711][INFO    ][13079] Returning information for job: 20190602072413292760
2019-06-02 07:24:38,910 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (470.152487993s left)
2019-06-02 07:24:43,464 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072443451382
2019-06-02 07:24:43,487 [salt.minion      :1432][INFO    ][13131] Starting a new job with PID 13131
2019-06-02 07:24:43,518 [salt.minion      :1711][INFO    ][13131] Returning information for job: 20190602072443451382
2019-06-02 07:25:11,909 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (437.153542995s left)
2019-06-02 07:25:13,631 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072513618615
2019-06-02 07:25:13,661 [salt.minion      :1432][INFO    ][13162] Starting a new job with PID 13162
2019-06-02 07:25:13,692 [salt.minion      :1711][INFO    ][13162] Returning information for job: 20190602072513618615
2019-06-02 07:25:43,836 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072543825530
2019-06-02 07:25:43,857 [salt.minion      :1432][INFO    ][13210] Starting a new job with PID 13210
2019-06-02 07:25:43,885 [salt.minion      :1711][INFO    ][13210] Returning information for job: 20190602072543825530
2019-06-02 07:25:44,769 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (404.293839931s left)
2019-06-02 07:26:14,025 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072614011870
2019-06-02 07:26:14,052 [salt.minion      :1432][INFO    ][13366] Starting a new job with PID 13366
2019-06-02 07:26:14,084 [salt.minion      :1711][INFO    ][13366] Returning information for job: 20190602072614011870
2019-06-02 07:26:17,592 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (371.471148014s left)
2019-06-02 07:26:44,249 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072644233268
2019-06-02 07:26:44,278 [salt.minion      :1432][INFO    ][13425] Starting a new job with PID 13425
2019-06-02 07:26:44,309 [salt.minion      :1711][INFO    ][13425] Returning information for job: 20190602072644233268
2019-06-02 07:26:50,463 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (338.599444866s left)
2019-06-02 07:27:14,419 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072714403789
2019-06-02 07:27:14,444 [salt.minion      :1432][INFO    ][13454] Starting a new job with PID 13454
2019-06-02 07:27:14,477 [salt.minion      :1711][INFO    ][13454] Returning information for job: 20190602072714403789
2019-06-02 07:27:23,277 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (305.785984993s left)
2019-06-02 07:27:44,464 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072744449108
2019-06-02 07:27:44,492 [salt.minion      :1432][INFO    ][13502] Starting a new job with PID 13502
2019-06-02 07:27:44,522 [salt.minion      :1711][INFO    ][13502] Returning information for job: 20190602072744449108
2019-06-02 07:27:56,016 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (273.046283007s left)
2019-06-02 07:28:14,516 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072814506853
2019-06-02 07:28:14,541 [salt.minion      :1432][INFO    ][13537] Starting a new job with PID 13537
2019-06-02 07:28:14,574 [salt.minion      :1711][INFO    ][13537] Returning information for job: 20190602072814506853
2019-06-02 07:28:28,936 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (240.127199888s left)
2019-06-02 07:28:44,607 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072844590902
2019-06-02 07:28:44,636 [salt.minion      :1432][INFO    ][13581] Starting a new job with PID 13581
2019-06-02 07:28:44,666 [salt.minion      :1711][INFO    ][13581] Returning information for job: 20190602072844590902
2019-06-02 07:29:01,917 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (207.145555973s left)
2019-06-02 07:29:14,657 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072914645378
2019-06-02 07:29:14,686 [salt.minion      :1432][INFO    ][13613] Starting a new job with PID 13613
2019-06-02 07:29:14,717 [salt.minion      :1711][INFO    ][13613] Returning information for job: 20190602072914645378
2019-06-02 07:29:34,673 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (174.390179873s left)
2019-06-02 07:29:44,800 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602072944784874
2019-06-02 07:29:44,824 [salt.minion      :1432][INFO    ][13659] Starting a new job with PID 13659
2019-06-02 07:29:44,855 [salt.minion      :1711][INFO    ][13659] Returning information for job: 20190602072944784874
2019-06-02 07:30:07,547 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (141.515558004s left)
2019-06-02 07:30:14,877 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073014866912
2019-06-02 07:30:14,904 [salt.minion      :1432][INFO    ][13689] Starting a new job with PID 13689
2019-06-02 07:30:14,938 [salt.minion      :1711][INFO    ][13689] Returning information for job: 20190602073014866912
2019-06-02 07:30:40,419 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (108.643244982s left)
2019-06-02 07:30:45,019 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073045006299
2019-06-02 07:30:45,047 [salt.minion      :1432][INFO    ][13732] Starting a new job with PID 13732
2019-06-02 07:30:45,077 [salt.minion      :1711][INFO    ][13732] Returning information for job: 20190602073045006299
2019-06-02 07:31:13,184 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (75.8785698414s left)
2019-06-02 07:31:15,141 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073115133608
2019-06-02 07:31:15,163 [salt.minion      :1432][INFO    ][13761] Starting a new job with PID 13761
2019-06-02 07:31:15,193 [salt.minion      :1711][INFO    ][13761] Returning information for job: 20190602073115133608
2019-06-02 07:31:45,348 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073145335459
2019-06-02 07:31:45,379 [salt.minion      :1432][INFO    ][13807] Starting a new job with PID 13807
2019-06-02 07:31:45,407 [salt.minion      :1711][INFO    ][13807] Returning information for job: 20190602073145335459
2019-06-02 07:31:45,872 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (43.1904020309s left)
2019-06-02 07:32:15,585 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073215570071
2019-06-02 07:32:15,612 [salt.minion      :1432][INFO    ][13835] Starting a new job with PID 13835
2019-06-02 07:32:15,645 [salt.minion      :1711][INFO    ][13835] Returning information for job: 20190602073215570071
2019-06-02 07:32:18,632 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (10.4307780266s left)
2019-06-02 07:32:45,715 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073245701042
2019-06-02 07:32:45,742 [salt.minion      :1432][INFO    ][13881] Starting a new job with PID 13881
2019-06-02 07:32:45,773 [salt.minion      :1711][INFO    ][13881] Returning information for job: 20190602073245701042
2019-06-02 07:32:51,453 [salt.state       :302 ][ERROR   ][8281] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-02 07:32:51,454 [salt.state       :1951][INFO    ][8281] Completed state [maas.wait_for_machine_status] at time 07:32:51.454131 duration_in_ms=2272401.953
2019-06-02 07:32:51,465 [salt.minion      :1711][INFO    ][8281] Returning information for job: 20190602065451248697
2019-06-02 07:33:02,491 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command pillar.get with jid 20190602073302479454
2019-06-02 07:33:02,520 [salt.minion      :1432][INFO    ][13901] Starting a new job with PID 13901
2019-06-02 07:33:02,530 [salt.minion      :1711][INFO    ][13901] Returning information for job: 20190602073302479454
2019-06-02 07:33:03,275 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command service.status with jid 20190602073303262716
2019-06-02 07:33:03,303 [salt.minion      :1432][INFO    ][13906] Starting a new job with PID 13906
2019-06-02 07:33:04,121 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13906] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-02 07:33:04,162 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13906] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-02 07:33:04,185 [salt.minion      :1711][INFO    ][13906] Returning information for job: 20190602073303262716
2019-06-02 07:33:04,915 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602073304906168
2019-06-02 07:33:04,943 [salt.minion      :1432][INFO    ][13917] Starting a new job with PID 13917
2019-06-02 07:33:11,078 [salt.state       :915 ][INFO    ][13917] Loading fresh modules for state activity
2019-06-02 07:33:11,734 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command 'salt-minion --version' in directory '/root'
2019-06-02 07:33:12,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command 'salt-minion --version' in directory '/root'
2019-06-02 07:33:13,179 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command 'salt-minion --version' in directory '/root'
2019-06-02 07:33:13,542 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command 'salt-minion --version' in directory '/root'
2019-06-02 07:33:15,851 [salt.state       :1780][INFO    ][13917] Running state [salt-minion] at time 07:33:15.851636
2019-06-02 07:33:15,852 [salt.state       :1813][INFO    ][13917] Executing state pkg.installed for [salt-minion]
2019-06-02 07:33:15,852 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-02 07:33:15,979 [salt.state       :300 ][INFO    ][13917] All specified packages are already installed
2019-06-02 07:33:15,979 [salt.state       :1951][INFO    ][13917] Completed state [salt-minion] at time 07:33:15.979662 duration_in_ms=128.026
2019-06-02 07:33:15,980 [salt.state       :1780][INFO    ][13917] Running state [salt_minion_dependency_packages] at time 07:33:15.980022
2019-06-02 07:33:15,980 [salt.state       :1813][INFO    ][13917] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-02 07:33:15,990 [salt.state       :300 ][INFO    ][13917] All specified packages are already installed
2019-06-02 07:33:15,990 [salt.state       :1951][INFO    ][13917] Completed state [salt_minion_dependency_packages] at time 07:33:15.990504 duration_in_ms=10.482
2019-06-02 07:33:15,993 [salt.state       :1780][INFO    ][13917] Running state [/etc/salt/minion.d/minion.conf] at time 07:33:15.993895
2019-06-02 07:33:15,994 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-02 07:33:16,285 [salt.state       :300 ][INFO    ][13917] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-02 07:33:16,286 [salt.state       :1951][INFO    ][13917] Completed state [/etc/salt/minion.d/minion.conf] at time 07:33:16.286199 duration_in_ms=292.304
2019-06-02 07:33:16,286 [salt.state       :1780][INFO    ][13917] Running state [python-netaddr] at time 07:33:16.286496
2019-06-02 07:33:16,286 [salt.state       :1813][INFO    ][13917] Executing state pkg.installed for [python-netaddr]
2019-06-02 07:33:16,296 [salt.state       :300 ][INFO    ][13917] All specified packages are already installed
2019-06-02 07:33:16,296 [salt.state       :1951][INFO    ][13917] Completed state [python-netaddr] at time 07:33:16.296218 duration_in_ms=9.721
2019-06-02 07:33:16,300 [salt.state       :1780][INFO    ][13917] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 07:33:16.300007
2019-06-02 07:33:16,300 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-02 07:33:16,312 [salt.state       :300 ][INFO    ][13917] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-02 07:33:16,312 [salt.state       :1951][INFO    ][13917] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 07:33:16.312441 duration_in_ms=12.433
2019-06-02 07:33:16,313 [salt.state       :1780][INFO    ][13917] Running state [salt-minion] at time 07:33:16.313618
2019-06-02 07:33:16,314 [salt.state       :1813][INFO    ][13917] Executing state service.running for [salt-minion]
2019-06-02 07:33:16,315 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-02 07:33:16,359 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-02 07:33:16,382 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-02 07:33:16,404 [salt.state       :300 ][INFO    ][13917] The service salt-minion is already running
2019-06-02 07:33:16,404 [salt.state       :1951][INFO    ][13917] Completed state [salt-minion] at time 07:33:16.404814 duration_in_ms=91.194
2019-06-02 07:33:16,408 [salt.state       :1780][INFO    ][13917] Running state [/etc/salt/grains.d] at time 07:33:16.408003
2019-06-02 07:33:16,408 [salt.state       :1813][INFO    ][13917] Executing state file.directory for [/etc/salt/grains.d]
2019-06-02 07:33:16,411 [salt.state       :300 ][INFO    ][13917] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-02 07:33:16,412 [salt.state       :1951][INFO    ][13917] Completed state [/etc/salt/grains.d] at time 07:33:16.412009 duration_in_ms=4.006
2019-06-02 07:33:16,413 [salt.state       :1780][INFO    ][13917] Running state [/etc/salt/grains] at time 07:33:16.412989
2019-06-02 07:33:16,413 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/salt/grains]
2019-06-02 07:33:16,414 [salt.state       :300 ][INFO    ][13917] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-02 07:33:16,415 [salt.state       :1951][INFO    ][13917] Completed state [/etc/salt/grains] at time 07:33:16.415073 duration_in_ms=2.083
2019-06-02 07:33:16,415 [salt.state       :1780][INFO    ][13917] Running state [/etc/salt/grains.d/placeholder] at time 07:33:16.415781
2019-06-02 07:33:16,416 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-02 07:33:16,417 [salt.state       :300 ][INFO    ][13917] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-02 07:33:16,417 [salt.state       :1951][INFO    ][13917] Completed state [/etc/salt/grains.d/placeholder] at time 07:33:16.417271 duration_in_ms=1.491
2019-06-02 07:33:16,418 [salt.state       :1780][INFO    ][13917] Running state [/etc/salt/grains.d/sphinx] at time 07:33:16.417982
2019-06-02 07:33:16,418 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-02 07:33:16,420 [salt.state       :300 ][INFO    ][13917] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-02 07:33:16,420 [salt.state       :1951][INFO    ][13917] Completed state [/etc/salt/grains.d/sphinx] at time 07:33:16.420814 duration_in_ms=2.832
2019-06-02 07:33:16,425 [salt.state       :1780][INFO    ][13917] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:33:16.425281
2019-06-02 07:33:16,425 [salt.state       :1813][INFO    ][13917] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-02 07:33:16,426 [salt.state       :300 ][INFO    ][13917] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-02 07:33:16,426 [salt.state       :1951][INFO    ][13917] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:33:16.426635 duration_in_ms=1.354
2019-06-02 07:33:16,427 [salt.state       :1780][INFO    ][13917] Running state [/etc/salt/grains.d/dns_records] at time 07:33:16.427413
2019-06-02 07:33:16,427 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-02 07:33:16,430 [salt.state       :300 ][INFO    ][13917] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-02 07:33:16,430 [salt.state       :1951][INFO    ][13917] Completed state [/etc/salt/grains.d/dns_records] at time 07:33:16.430528 duration_in_ms=3.115
2019-06-02 07:33:16,432 [salt.state       :1780][INFO    ][13917] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 07:33:16.432268
2019-06-02 07:33:16,432 [salt.state       :1813][INFO    ][13917] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-02 07:33:16,433 [salt.state       :300 ][INFO    ][13917] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-02 07:33:16,433 [salt.state       :1951][INFO    ][13917] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 07:33:16.433564 duration_in_ms=1.297
2019-06-02 07:33:16,435 [salt.state       :1780][INFO    ][13917] Running state [/etc/salt/grains.d/salt] at time 07:33:16.435571
2019-06-02 07:33:16,436 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-02 07:33:16,438 [salt.state       :300 ][INFO    ][13917] File /etc/salt/grains.d/salt is in the correct state
2019-06-02 07:33:16,438 [salt.state       :1951][INFO    ][13917] Completed state [/etc/salt/grains.d/salt] at time 07:33:16.438764 duration_in_ms=3.193
2019-06-02 07:33:16,440 [salt.state       :1780][INFO    ][13917] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 07:33:16.440499
2019-06-02 07:33:16,441 [salt.state       :1813][INFO    ][13917] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-02 07:33:16,441 [salt.state       :300 ][INFO    ][13917] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-02 07:33:16,442 [salt.state       :1951][INFO    ][13917] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 07:33:16.442316 duration_in_ms=1.817
2019-06-02 07:33:16,446 [salt.state       :1780][INFO    ][13917] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:33:16.446919
2019-06-02 07:33:16,447 [salt.state       :1813][INFO    ][13917] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-02 07:33:16,447 [salt.state       :300 ][INFO    ][13917] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-02 07:33:16,447 [salt.state       :1951][INFO    ][13917] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:33:16.447761 duration_in_ms=0.842
2019-06-02 07:33:16,448 [salt.state       :1780][INFO    ][13917] Running state [mine.update] at time 07:33:16.448451
2019-06-02 07:33:16,448 [salt.state       :1813][INFO    ][13917] Executing state module.wait for [mine.update]
2019-06-02 07:33:16,449 [salt.state       :300 ][INFO    ][13917] No changes made for mine.update
2019-06-02 07:33:16,449 [salt.state       :1951][INFO    ][13917] Completed state [mine.update] at time 07:33:16.449240 duration_in_ms=0.789
2019-06-02 07:33:16,449 [salt.state       :1780][INFO    ][13917] Running state [ca-certificates] at time 07:33:16.449513
2019-06-02 07:33:16,450 [salt.state       :1813][INFO    ][13917] Executing state pkg.installed for [ca-certificates]
2019-06-02 07:33:16,460 [salt.state       :300 ][INFO    ][13917] All specified packages are already installed
2019-06-02 07:33:16,460 [salt.state       :1951][INFO    ][13917] Completed state [ca-certificates] at time 07:33:16.460210 duration_in_ms=10.697
2019-06-02 07:33:16,460 [salt.state       :1780][INFO    ][13917] Running state [update-ca-certificates] at time 07:33:16.460927
2019-06-02 07:33:16,461 [salt.state       :1813][INFO    ][13917] Executing state cmd.wait for [update-ca-certificates]
2019-06-02 07:33:16,461 [salt.state       :300 ][INFO    ][13917] No changes made for update-ca-certificates
2019-06-02 07:33:16,461 [salt.state       :1951][INFO    ][13917] Completed state [update-ca-certificates] at time 07:33:16.461749 duration_in_ms=0.822
2019-06-02 07:33:16,462 [salt.state       :1780][INFO    ][13917] Running state [iptables] at time 07:33:16.462041
2019-06-02 07:33:16,462 [salt.state       :1813][INFO    ][13917] Executing state pkg.installed for [iptables]
2019-06-02 07:33:16,472 [salt.state       :300 ][INFO    ][13917] All specified packages are already installed
2019-06-02 07:33:16,472 [salt.state       :1951][INFO    ][13917] Completed state [iptables] at time 07:33:16.472338 duration_in_ms=10.297
2019-06-02 07:33:16,472 [salt.state       :1780][INFO    ][13917] Running state [iptables-persistent] at time 07:33:16.472624
2019-06-02 07:33:16,472 [salt.state       :1813][INFO    ][13917] Executing state pkg.installed for [iptables-persistent]
2019-06-02 07:33:16,482 [salt.state       :300 ][INFO    ][13917] All specified packages are already installed
2019-06-02 07:33:16,482 [salt.state       :1951][INFO    ][13917] Completed state [iptables-persistent] at time 07:33:16.482478 duration_in_ms=9.853
2019-06-02 07:33:16,484 [salt.state       :1780][INFO    ][13917] Running state [iptables_modules_v4_load] at time 07:33:16.484514
2019-06-02 07:33:16,485 [salt.state       :1813][INFO    ][13917] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-02 07:33:16,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command 'lsmod' in directory '/root'
2019-06-02 07:33:16,513 [salt.state       :300 ][INFO    ][13917] Kernel modules iptable_filter, ip_tables are already present
2019-06-02 07:33:16,515 [salt.state       :1951][INFO    ][13917] Completed state [iptables_modules_v4_load] at time 07:33:16.514993 duration_in_ms=30.48
2019-06-02 07:33:16,516 [salt.state       :1780][INFO    ][13917] Running state [/etc/iptables/rules.v4] at time 07:33:16.516157
2019-06-02 07:33:16,516 [salt.state       :1813][INFO    ][13917] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-02 07:33:16,629 [salt.state       :300 ][INFO    ][13917] File /etc/iptables/rules.v4 is in the correct state
2019-06-02 07:33:16,630 [salt.state       :1951][INFO    ][13917] Completed state [/etc/iptables/rules.v4] at time 07:33:16.630053 duration_in_ms=113.897
2019-06-02 07:33:16,631 [salt.state       :1780][INFO    ][13917] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 07:33:16.631153
2019-06-02 07:33:16,631 [salt.state       :1813][INFO    ][13917] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-02 07:33:16,632 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-02 07:33:16,650 [salt.state       :300 ][INFO    ][13917] onlyif execution failed
2019-06-02 07:33:16,650 [salt.state       :1951][INFO    ][13917] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 07:33:16.650821 duration_in_ms=19.666
2019-06-02 07:33:16,652 [salt.state       :1780][INFO    ][13917] Running state [netfilter-persistent] at time 07:33:16.652601
2019-06-02 07:33:16,653 [salt.state       :1813][INFO    ][13917] Executing state service.running for [netfilter-persistent]
2019-06-02 07:33:16,654 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-02 07:33:16,679 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-02 07:33:16,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-02 07:33:16,724 [salt.state       :300 ][INFO    ][13917] The service netfilter-persistent is already running
2019-06-02 07:33:16,724 [salt.state       :1951][INFO    ][13917] Completed state [netfilter-persistent] at time 07:33:16.724882 duration_in_ms=72.281
2019-06-02 07:33:16,726 [salt.state       :1780][INFO    ][13917] Running state [iptables_extra.remove_stale_tables] at time 07:33:16.726453
2019-06-02 07:33:16,727 [salt.state       :1813][INFO    ][13917] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-02 07:33:16,727 [salt.state       :300 ][INFO    ][13917] No changes made for iptables_extra.remove_stale_tables
2019-06-02 07:33:16,728 [salt.state       :1951][INFO    ][13917] Completed state [iptables_extra.remove_stale_tables] at time 07:33:16.728153 duration_in_ms=1.699
2019-06-02 07:33:16,728 [salt.state       :1780][INFO    ][13917] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 07:33:16.728677
2019-06-02 07:33:16,729 [salt.state       :1813][INFO    ][13917] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-02 07:33:16,731 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13917] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-02 07:33:16,747 [salt.state       :300 ][INFO    ][13917] onlyif execution failed
2019-06-02 07:33:16,748 [salt.state       :1951][INFO    ][13917] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 07:33:16.748516 duration_in_ms=19.838
2019-06-02 07:33:16,750 [salt.state       :1780][INFO    ][13917] Running state [/etc/iptables/rules.v6] at time 07:33:16.750695
2019-06-02 07:33:16,751 [salt.state       :1813][INFO    ][13917] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-02 07:33:16,752 [salt.state       :300 ][INFO    ][13917] File /etc/iptables/rules.v6 is not present
2019-06-02 07:33:16,752 [salt.state       :1951][INFO    ][13917] Completed state [/etc/iptables/rules.v6] at time 07:33:16.752600 duration_in_ms=1.906
2019-06-02 07:33:16,757 [salt.state       :1780][INFO    ][13917] Running state [iptables_extra.flush_all] at time 07:33:16.756954
2019-06-02 07:33:16,757 [salt.state       :1813][INFO    ][13917] Executing state module.wait for [iptables_extra.flush_all]
2019-06-02 07:33:16,757 [salt.state       :300 ][INFO    ][13917] No changes made for iptables_extra.flush_all
2019-06-02 07:33:16,757 [salt.state       :1951][INFO    ][13917] Completed state [iptables_extra.flush_all] at time 07:33:16.757808 duration_in_ms=0.854
2019-06-02 07:33:16,761 [salt.minion      :1711][INFO    ][13917] Returning information for job: 20190602073304906168
2019-06-02 07:33:17,473 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602073317464437
2019-06-02 07:33:17,497 [salt.minion      :1432][INFO    ][14006] Starting a new job with PID 14006
2019-06-02 07:33:18,449 [salt.state       :915 ][INFO    ][14006] Loading fresh modules for state activity
2019-06-02 07:33:19,745 [salt.state       :1780][INFO    ][14006] Running state [maas-rack-controller] at time 07:33:19.745150
2019-06-02 07:33:19,745 [salt.state       :1813][INFO    ][14006] Executing state pkg.installed for [maas-rack-controller]
2019-06-02 07:33:19,747 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14006] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-02 07:33:19,877 [salt.state       :300 ][INFO    ][14006] All specified packages are already installed
2019-06-02 07:33:19,878 [salt.state       :1951][INFO    ][14006] Completed state [maas-rack-controller] at time 07:33:19.878017 duration_in_ms=132.867
2019-06-02 07:33:19,878 [salt.state       :1780][INFO    ][14006] Running state [ipmitool] at time 07:33:19.878366
2019-06-02 07:33:19,878 [salt.state       :1813][INFO    ][14006] Executing state pkg.installed for [ipmitool]
2019-06-02 07:33:19,888 [salt.state       :300 ][INFO    ][14006] All specified packages are already installed
2019-06-02 07:33:19,888 [salt.state       :1951][INFO    ][14006] Completed state [ipmitool] at time 07:33:19.888294 duration_in_ms=9.928
2019-06-02 07:33:19,891 [salt.state       :1780][INFO    ][14006] Running state [/etc/maas/rackd.conf] at time 07:33:19.891429
2019-06-02 07:33:19,891 [salt.state       :1813][INFO    ][14006] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-02 07:33:19,892 [salt.state       :300 ][INFO    ][14006] No changes needed to be made
2019-06-02 07:33:19,892 [salt.state       :1951][INFO    ][14006] Completed state [/etc/maas/rackd.conf] at time 07:33:19.892905 duration_in_ms=1.475
2019-06-02 07:33:19,893 [salt.state       :1780][INFO    ][14006] Running state [/etc/maas/rackd.conf] at time 07:33:19.893183
2019-06-02 07:33:19,893 [salt.state       :1813][INFO    ][14006] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-02 07:33:19,893 [salt.loaded.int.states.file:2298][WARNING ][14006] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-02 07:33:19,894 [salt.state       :300 ][INFO    ][14006] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-02 07:33:19,894 [salt.state       :1951][INFO    ][14006] Completed state [/etc/maas/rackd.conf] at time 07:33:19.894811 duration_in_ms=1.628
2019-06-02 07:33:19,895 [salt.state       :1780][INFO    ][14006] Running state [maas-rackd] at time 07:33:19.895847
2019-06-02 07:33:19,896 [salt.state       :1813][INFO    ][14006] Executing state service.running for [maas-rackd]
2019-06-02 07:33:19,896 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14006] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-02 07:33:19,941 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14006] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-02 07:33:19,966 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14006] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-02 07:33:19,990 [salt.state       :300 ][INFO    ][14006] The service maas-rackd is already running
2019-06-02 07:33:19,991 [salt.state       :1951][INFO    ][14006] Completed state [maas-rackd] at time 07:33:19.991056 duration_in_ms=95.208
2019-06-02 07:33:19,995 [salt.minion      :1711][INFO    ][14006] Returning information for job: 20190602073317464437
2019-06-02 07:33:20,741 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602073320729540
2019-06-02 07:33:20,769 [salt.minion      :1432][INFO    ][14029] Starting a new job with PID 14029
2019-06-02 07:33:21,730 [salt.state       :915 ][INFO    ][14029] Loading fresh modules for state activity
2019-06-02 07:33:23,302 [salt.state       :1780][INFO    ][14029] Running state [maas-region-controller] at time 07:33:23.302536
2019-06-02 07:33:23,303 [salt.state       :1813][INFO    ][14029] Executing state pkg.installed for [maas-region-controller]
2019-06-02 07:33:23,303 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-02 07:33:23,441 [salt.state       :300 ][INFO    ][14029] All specified packages are already installed
2019-06-02 07:33:23,441 [salt.state       :1951][INFO    ][14029] Completed state [maas-region-controller] at time 07:33:23.441356 duration_in_ms=138.821
2019-06-02 07:33:23,441 [salt.state       :1780][INFO    ][14029] Running state [python-oauth] at time 07:33:23.441727
2019-06-02 07:33:23,442 [salt.state       :1813][INFO    ][14029] Executing state pkg.installed for [python-oauth]
2019-06-02 07:33:23,452 [salt.state       :300 ][INFO    ][14029] All specified packages are already installed
2019-06-02 07:33:23,453 [salt.state       :1951][INFO    ][14029] Completed state [python-oauth] at time 07:33:23.453110 duration_in_ms=11.382
2019-06-02 07:33:23,456 [salt.state       :1780][INFO    ][14029] Running state [/etc/maas/regiond.conf] at time 07:33:23.456549
2019-06-02 07:33:23,456 [salt.state       :1813][INFO    ][14029] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-02 07:33:23,461 [salt.state       :300 ][INFO    ][14029] No changes needed to be made
2019-06-02 07:33:23,463 [salt.state       :1951][INFO    ][14029] Completed state [/etc/maas/regiond.conf] at time 07:33:23.463877 duration_in_ms=7.328
2019-06-02 07:33:23,464 [salt.state       :1780][INFO    ][14029] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:33:23.464397
2019-06-02 07:33:23,464 [salt.state       :1813][INFO    ][14029] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-02 07:33:23,537 [salt.state       :300 ][INFO    ][14029] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-02 07:33:23,537 [salt.state       :1951][INFO    ][14029] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:33:23.537675 duration_in_ms=73.278
2019-06-02 07:33:23,538 [salt.state       :1780][INFO    ][14029] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:33:23.538225
2019-06-02 07:33:23,538 [salt.state       :1813][INFO    ][14029] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-02 07:33:23,542 [salt.state       :300 ][INFO    ][14029] No changes needed to be made
2019-06-02 07:33:23,543 [salt.state       :1951][INFO    ][14029] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:33:23.543002 duration_in_ms=4.777
2019-06-02 07:33:23,543 [salt.state       :1780][INFO    ][14029] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:33:23.543513
2019-06-02 07:33:23,543 [salt.state       :1813][INFO    ][14029] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-02 07:33:23,549 [salt.state       :300 ][INFO    ][14029] No changes needed to be made
2019-06-02 07:33:23,549 [salt.state       :1951][INFO    ][14029] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:33:23.549805 duration_in_ms=6.291
2019-06-02 07:33:23,550 [salt.state       :1780][INFO    ][14029] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:33:23.550319
2019-06-02 07:33:23,550 [salt.state       :1813][INFO    ][14029] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-02 07:33:23,584 [salt.state       :300 ][INFO    ][14029] No changes needed to be made
2019-06-02 07:33:23,585 [salt.state       :1951][INFO    ][14029] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:33:23.585020 duration_in_ms=34.7
2019-06-02 07:33:23,585 [salt.state       :1780][INFO    ][14029] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:33:23.585629
2019-06-02 07:33:23,586 [salt.state       :1813][INFO    ][14029] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-02 07:33:23,602 [salt.state       :300 ][INFO    ][14029] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-02 07:33:23,603 [salt.state       :1951][INFO    ][14029] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:33:23.603084 duration_in_ms=17.454
2019-06-02 07:33:23,605 [salt.state       :1780][INFO    ][14029] Running state [a2enmod headers] at time 07:33:23.605527
2019-06-02 07:33:23,608 [salt.state       :1813][INFO    ][14029] Executing state cmd.run for [a2enmod headers]
2019-06-02 07:33:23,609 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command 'a2enmod headers' in directory '/root'
2019-06-02 07:33:23,685 [salt.state       :300 ][INFO    ][14029] {'pid': 14075, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-02 07:33:23,686 [salt.state       :1951][INFO    ][14029] Completed state [a2enmod headers] at time 07:33:23.686553 duration_in_ms=81.026
2019-06-02 07:33:23,687 [salt.state       :1780][INFO    ][14029] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:33:23.687344
2019-06-02 07:33:23,688 [salt.state       :1813][INFO    ][14029] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-02 07:33:23,710 [salt.state       :300 ][INFO    ][14029] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-02 07:33:23,711 [salt.state       :1951][INFO    ][14029] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:33:23.711111 duration_in_ms=23.768
2019-06-02 07:33:23,711 [salt.state       :1780][INFO    ][14029] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:33:23.711912
2019-06-02 07:33:23,712 [salt.state       :1813][INFO    ][14029] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-02 07:33:23,772 [salt.state       :300 ][INFO    ][14029] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-02 07:33:23,772 [salt.state       :1951][INFO    ][14029] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:33:23.772340 duration_in_ms=60.428
2019-06-02 07:33:23,772 [salt.state       :1780][INFO    ][14029] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:33:23.772912
2019-06-02 07:33:23,773 [salt.state       :1813][INFO    ][14029] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-02 07:33:23,843 [salt.state       :300 ][INFO    ][14029] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-02 07:33:23,843 [salt.state       :1951][INFO    ][14029] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:33:23.843818 duration_in_ms=70.906
2019-06-02 07:33:23,844 [salt.state       :1780][INFO    ][14029] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:33:23.844345
2019-06-02 07:33:23,844 [salt.state       :1813][INFO    ][14029] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-02 07:33:23,916 [salt.state       :300 ][INFO    ][14029] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-02 07:33:23,916 [salt.state       :1951][INFO    ][14029] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:33:23.916568 duration_in_ms=72.223
2019-06-02 07:33:23,916 [salt.state       :1780][INFO    ][14029] Running state [/root/.pgpass] at time 07:33:23.916885
2019-06-02 07:33:23,917 [salt.state       :1813][INFO    ][14029] Executing state file.managed for [/root/.pgpass]
2019-06-02 07:33:23,968 [salt.state       :300 ][INFO    ][14029] File /root/.pgpass is in the correct state
2019-06-02 07:33:23,968 [salt.state       :1951][INFO    ][14029] Completed state [/root/.pgpass] at time 07:33:23.968799 duration_in_ms=51.914
2019-06-02 07:33:23,978 [salt.state       :1780][INFO    ][14029] Running state [maas-region syncdb --noinput] at time 07:33:23.978688
2019-06-02 07:33:23,979 [salt.state       :1813][INFO    ][14029] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-02 07:33:23,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-02 07:33:26,696 [salt.state       :300 ][INFO    ][14029] {'pid': 14088, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, sessions, contenttypes, piston3, metadataserver, sites, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-02 07:33:26,698 [salt.state       :1951][INFO    ][14029] Completed state [maas-region syncdb --noinput] at time 07:33:26.697533 duration_in_ms=2718.844
2019-06-02 07:33:26,698 [salt.state       :2022][WARNING ][14029] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-02 07:33:26,703 [salt.state       :1780][INFO    ][14029] Running state [maas-regiond] at time 07:33:26.701711
2019-06-02 07:33:26,703 [salt.state       :1813][INFO    ][14029] Executing state service.running for [maas-regiond]
2019-06-02 07:33:26,705 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-02 07:33:26,744 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-02 07:33:26,761 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-02 07:33:26,781 [salt.state       :300 ][INFO    ][14029] The service maas-regiond is already running
2019-06-02 07:33:26,781 [salt.state       :1951][INFO    ][14029] Completed state [maas-regiond] at time 07:33:26.781675 duration_in_ms=79.964
2019-06-02 07:33:26,784 [salt.state       :1780][INFO    ][14029] Running state [bind9] at time 07:33:26.784118
2019-06-02 07:33:26,784 [salt.state       :1813][INFO    ][14029] Executing state service.running for [bind9]
2019-06-02 07:33:26,785 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-02 07:33:26,805 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-02 07:33:26,824 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-02 07:33:26,848 [salt.state       :300 ][INFO    ][14029] The service bind9 is already running
2019-06-02 07:33:26,849 [salt.state       :1951][INFO    ][14029] Completed state [bind9] at time 07:33:26.849406 duration_in_ms=65.287
2019-06-02 07:33:26,853 [salt.state       :1780][INFO    ][14029] Running state [apache2] at time 07:33:26.852908
2019-06-02 07:33:26,853 [salt.state       :1813][INFO    ][14029] Executing state service.running for [apache2]
2019-06-02 07:33:26,855 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-02 07:33:26,878 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-02 07:33:26,898 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-02 07:33:26,924 [salt.state       :300 ][INFO    ][14029] The service apache2 is already running
2019-06-02 07:33:26,925 [salt.state       :1951][INFO    ][14029] Completed state [apache2] at time 07:33:26.924918 duration_in_ms=72.01
2019-06-02 07:33:26,927 [salt.state       :1780][INFO    ][14029] Running state [maasng.wait_for_http_code] at time 07:33:26.926945
2019-06-02 07:33:26,927 [salt.state       :1813][INFO    ][14029] Executing state module.run for [maasng.wait_for_http_code]
2019-06-02 07:33:26,928 [salt.utils.decorators:613 ][WARNING ][14029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 07:33:27,052 [salt.state       :300 ][INFO    ][14029] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-02 07:33:27,053 [salt.state       :1951][INFO    ][14029] Completed state [maasng.wait_for_http_code] at time 07:33:27.052994 duration_in_ms=126.05
2019-06-02 07:33:27,054 [salt.state       :1780][INFO    ][14029] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:33:27.054511
2019-06-02 07:33:27,054 [salt.state       :1813][INFO    ][14029] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-02 07:33:27,055 [salt.state       :300 ][INFO    ][14029] /var/lib/maas/.setup_admin exists
2019-06-02 07:33:27,055 [salt.state       :1951][INFO    ][14029] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:33:27.055544 duration_in_ms=1.033
2019-06-02 07:33:27,056 [salt.state       :1780][INFO    ][14029] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:33:27.056247
2019-06-02 07:33:27,056 [salt.state       :1813][INFO    ][14029] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 07:33:27,057 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14029] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 07:33:28,911 [salt.state       :300 ][INFO    ][14029] {'pid': 14114, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 07:33:28,911 [salt.state       :1951][INFO    ][14029] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:33:28.911843 duration_in_ms=1855.596
2019-06-02 07:33:28,917 [salt.state       :1780][INFO    ][14029] Running state [maas_region_boot_source_resources_mirror] at time 07:33:28.917370
2019-06-02 07:33:28,917 [salt.state       :1813][INFO    ][14029] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-02 07:33:28,991 [salt.state       :300 ][INFO    ][14029] {'changes': {}}
2019-06-02 07:33:28,992 [salt.state       :1951][INFO    ][14029] Completed state [maas_region_boot_source_resources_mirror] at time 07:33:28.991895 duration_in_ms=74.525
2019-06-02 07:33:28,993 [salt.state       :1780][INFO    ][14029] Running state [maasng.boot_resources_import] at time 07:33:28.992940
2019-06-02 07:33:28,993 [salt.state       :1813][INFO    ][14029] Executing state module.run for [maasng.boot_resources_import]
2019-06-02 07:33:28,993 [salt.utils.decorators:613 ][WARNING ][14029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 07:33:29,082 [salt.loaded.ext.module.maasng:1600][INFO    ][14029] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-02 07:33:34,130 [salt.loaded.ext.module.maasng:1600][INFO    ][14029] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-02 07:33:35,792 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073335776540
2019-06-02 07:33:35,820 [salt.minion      :1432][INFO    ][14147] Starting a new job with PID 14147
2019-06-02 07:33:35,860 [salt.minion      :1711][INFO    ][14147] Returning information for job: 20190602073335776540
2019-06-02 07:33:39,249 [salt.state       :300 ][INFO    ][14029] {'ret': True}
2019-06-02 07:33:39,250 [salt.state       :1951][INFO    ][14029] Completed state [maasng.boot_resources_import] at time 07:33:39.249989 duration_in_ms=10257.048
2019-06-02 07:33:39,250 [salt.state       :1780][INFO    ][14029] Running state [maas_region_boot_sources_selection_xenial] at time 07:33:39.250884
2019-06-02 07:33:39,251 [salt.state       :1813][INFO    ][14029] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-02 07:33:39,431 [salt.state       :300 ][INFO    ][14029] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-02 07:33:39,432 [salt.state       :1951][INFO    ][14029] Completed state [maas_region_boot_sources_selection_xenial] at time 07:33:39.431886 duration_in_ms=181.0
2019-06-02 07:33:39,433 [salt.state       :1780][INFO    ][14029] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 07:33:39.433505
2019-06-02 07:33:39,434 [salt.state       :1813][INFO    ][14029] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-02 07:33:39,435 [salt.utils.decorators:613 ][WARNING ][14029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 07:33:39,435 [salt.loaded.ext.module.maasng:1771][INFO    ][14029] boot-sources sync initiated for ALL Rack's
2019-06-02 07:33:40,462 [salt.state       :300 ][INFO    ][14029] {'ret': True}
2019-06-02 07:33:40,463 [salt.state       :1951][INFO    ][14029] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 07:33:40.463370 duration_in_ms=1029.865
2019-06-02 07:33:40,465 [salt.state       :1780][INFO    ][14029] Running state [maas.process_maas_config] at time 07:33:40.465153
2019-06-02 07:33:40,465 [salt.state       :1813][INFO    ][14029] Executing state module.run for [maas.process_maas_config]
2019-06-02 07:33:40,467 [salt.utils.decorators:613 ][WARNING ][14029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 07:33:40,467 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=enable_http_proxy value=True
2019-06-02 07:33:40,519 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=upstream_dns value=8.8.8.8
2019-06-02 07:33:40,577 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=commissioning_distro_series value=xenial
2019-06-02 07:33:42,092 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=default_osystem value=ubuntu
2019-06-02 07:33:42,145 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=active_discovery_interval value=600
2019-06-02 07:33:42,189 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=dnssec_validation value=no
2019-06-02 07:33:42,243 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=maas_name value=mas01
2019-06-02 07:33:42,296 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=network_discovery value=enabled
2019-06-02 07:33:42,411 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=enable_third_party_drivers value=True
2019-06-02 07:33:42,467 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=default_storage_layout value=lvm
2019-06-02 07:33:42,515 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=ntp_external_only value=True
2019-06-02 07:33:42,560 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-02 07:33:42,611 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=default_distro_series value=xenial
2019-06-02 07:33:42,665 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-02 07:33:42,809 [salt.state       :300 ][INFO    ][14029] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-02 07:33:42,809 [salt.state       :1951][INFO    ][14029] Completed state [maas.process_maas_config] at time 07:33:42.809488 duration_in_ms=2344.334
2019-06-02 07:33:42,811 [salt.state       :1780][INFO    ][14029] Running state [pxe_admin] at time 07:33:42.811100
2019-06-02 07:33:42,811 [salt.state       :1813][INFO    ][14029] Executing state maasng.fabric_present for [pxe_admin]
2019-06-02 07:33:42,876 [salt.loaded.ext.module.maasng:945 ][INFO    ][14029] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'm8xw38', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-02 07:33:42,943 [salt.loaded.ext.module.maasng:1008][WARNING ][14029] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-02 07:33:42,943 [salt.loaded.ext.module.maasng:1011][WARNING ][14029] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-02 07:33:43,026 [salt.state       :300 ][INFO    ][14029] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-02 07:33:43,027 [salt.state       :1951][INFO    ][14029] Completed state [pxe_admin] at time 07:33:43.027269 duration_in_ms=216.168
2019-06-02 07:33:43,027 [salt.state       :1780][INFO    ][14029] Running state [vlan 0] at time 07:33:43.027775
2019-06-02 07:33:43,028 [salt.state       :1813][INFO    ][14029] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-02 07:33:43,082 [salt.loaded.ext.module.maasng:945 ][INFO    ][14029] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'm8xw38', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-02 07:33:43,192 [salt.loaded.ext.module.maasng:945 ][INFO    ][14029] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'm8xw38', 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'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-02 07:33:43,422 [salt.loaded.ext.module.maasng:945 ][INFO    ][14029] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'm8xw38', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-02 07:33:43,624 [salt.state       :300 ][INFO    ][14029] {'new': 'Vlan untagged was updated'}
2019-06-02 07:33:43,624 [salt.state       :1951][INFO    ][14029] Completed state [vlan 0] at time 07:33:43.624591 duration_in_ms=596.815
2019-06-02 07:33:43,626 [salt.state       :1780][INFO    ][14029] Running state [192.168.11.0/24] at time 07:33:43.626695
2019-06-02 07:33:43,627 [salt.state       :1813][INFO    ][14029] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-02 07:33:43,809 [salt.loaded.ext.module.maasng:945 ][INFO    ][14029] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'm8xw38', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-02 07:33:43,809 [salt.loaded.ext.module.maasng:1235][WARNING ][14029] Ignoring parameter vlan:0
2019-06-02 07:33:43,895 [salt.state       :300 ][INFO    ][14029] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-02 07:33:43,895 [salt.state       :1951][INFO    ][14029] Completed state [192.168.11.0/24] at time 07:33:43.895866 duration_in_ms=269.17
2019-06-02 07:33:43,897 [salt.state       :1780][INFO    ][14029] Running state [maas_create_iprange_1] at time 07:33:43.896914
2019-06-02 07:33:43,897 [salt.state       :1813][INFO    ][14029] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-02 07:33:43,947 [salt.state       :300 ][INFO    ][14029] Iprange maas_create_iprange_1 already exist.
2019-06-02 07:33:43,947 [salt.state       :1951][INFO    ][14029] Completed state [maas_create_iprange_1] at time 07:33:43.947307 duration_in_ms=50.394
2019-06-02 07:33:43,947 [salt.state       :1780][INFO    ][14029] Running state [vlan 0] at time 07:33:43.947632
2019-06-02 07:33:43,947 [salt.state       :1813][INFO    ][14029] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-02 07:33:43,995 [salt.loaded.ext.module.maasng:945 ][INFO    ][14029] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'm8xw38', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-02 07:33:44,087 [salt.loaded.ext.module.maasng:945 ][INFO    ][14029] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'm8xw38', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-02 07:33:44,310 [salt.loaded.ext.module.maasng:945 ][INFO    ][14029] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'm8xw38', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-02 07:33:44,396 [salt.state       :300 ][INFO    ][14029] {'new': 'Vlan untagged was updated'}
2019-06-02 07:33:44,397 [salt.state       :1951][INFO    ][14029] Completed state [vlan 0] at time 07:33:44.397261 duration_in_ms=449.628
2019-06-02 07:33:44,398 [salt.state       :1780][INFO    ][14029] Running state [opnfv] at time 07:33:44.398318
2019-06-02 07:33:44,398 [salt.state       :1813][INFO    ][14029] Executing state maasng.sshkey_present for [opnfv]
2019-06-02 07:33:44,460 [salt.loaded.ext.module.maasng:1903][INFO    ][14029] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-06-02 07:33:44,461 [salt.state       :300 ][INFO    ][14029] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-02 07:33:44,461 [salt.state       :1951][INFO    ][14029] Completed state [opnfv] at time 07:33:44.461491 duration_in_ms=63.172
2019-06-02 07:33:44,466 [salt.state       :1780][INFO    ][14029] Running state [maas.process_tags] at time 07:33:44.466840
2019-06-02 07:33:44,467 [salt.state       :1813][INFO    ][14029] Executing state module.run for [maas.process_tags]
2019-06-02 07:33:44,467 [salt.utils.decorators:613 ][WARNING ][14029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 07:33:44,507 [salt.loaded.ext.module.maas:92  ][INFO    ][14029] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-02 07:33:44,557 [salt.state       :300 ][INFO    ][14029] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-02 07:33:44,559 [salt.state       :1951][INFO    ][14029] Completed state [maas.process_tags] at time 07:33:44.559711 duration_in_ms=92.87
2019-06-02 07:33:44,562 [salt.minion      :1711][INFO    ][14029] Returning information for job: 20190602073320729540
2019-06-02 07:33:45,348 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602073345339307
2019-06-02 07:33:45,370 [salt.minion      :1432][INFO    ][14501] Starting a new job with PID 14501
2019-06-02 07:33:51,393 [salt.state       :915 ][INFO    ][14501] Loading fresh modules for state activity
2019-06-02 07:33:51,516 [salt.state       :1780][INFO    ][14501] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:33:51.516663
2019-06-02 07:33:51,517 [salt.state       :1813][INFO    ][14501] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 07:33:51,519 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14501] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 07:33:53,466 [salt.state       :300 ][INFO    ][14501] {'pid': 14526, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 07:33:53,467 [salt.state       :1951][INFO    ][14501] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:33:53.467599 duration_in_ms=1950.935
2019-06-02 07:33:53,471 [salt.state       :1780][INFO    ][14501] Running state [maas.process_machines] at time 07:33:53.471125
2019-06-02 07:33:53,471 [salt.state       :1813][INFO    ][14501] Executing state module.run for [maas.process_machines]
2019-06-02 07:33:53,473 [salt.utils.decorators:613 ][WARNING ][14501] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 07:33:54,172 [salt.loaded.ext.module.maas:412 ][WARNING ][14501] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 07:33:54,173 [salt.loaded.ext.module.maas:92  ][INFO    ][14501] 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=xrkf7b architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-02 07:33:55,416 [salt.loaded.ext.module.maas:412 ][WARNING ][14501] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 07:33:55,416 [salt.loaded.ext.module.maas:92  ][INFO    ][14501] 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=dsn4np architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-02 07:33:56,623 [salt.loaded.ext.module.maas:412 ][WARNING ][14501] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 07:33:56,624 [salt.loaded.ext.module.maas:92  ][INFO    ][14501] 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=a67pgw architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-02 07:33:57,876 [salt.loaded.ext.module.maas:412 ][WARNING ][14501] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 07:33:57,879 [salt.loaded.ext.module.maas:92  ][INFO    ][14501] 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=b4rwmb architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-02 07:33:59,108 [salt.loaded.ext.module.maas:412 ][WARNING ][14501] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 07:33:59,109 [salt.loaded.ext.module.maas:92  ][INFO    ][14501] 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=pa4ar3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-02 07:34:00,301 [salt.state       :300 ][INFO    ][14501] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-02 07:34:00,303 [salt.state       :1951][INFO    ][14501] Completed state [maas.process_machines] at time 07:34:00.303289 duration_in_ms=6832.164
2019-06-02 07:34:00,308 [salt.minion      :1711][INFO    ][14501] Returning information for job: 20190602073345339307
2019-06-02 07:34:34,308 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602073434296290
2019-06-02 07:34:34,330 [salt.minion      :1432][INFO    ][14797] Starting a new job with PID 14797
2019-06-02 07:34:40,314 [salt.state       :915 ][INFO    ][14797] Loading fresh modules for state activity
2019-06-02 07:34:40,428 [salt.state       :1780][INFO    ][14797] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:34:40.428751
2019-06-02 07:34:40,429 [salt.state       :1813][INFO    ][14797] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 07:34:40,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14797] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 07:34:42,265 [salt.state       :300 ][INFO    ][14797] {'pid': 14808, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 07:34:42,266 [salt.state       :1951][INFO    ][14797] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:34:42.266634 duration_in_ms=1837.883
2019-06-02 07:34:42,270 [salt.state       :1780][INFO    ][14797] Running state [maas.wait_for_machine_status] at time 07:34:42.270119
2019-06-02 07:34:42,270 [salt.state       :1813][INFO    ][14797] Executing state module.run for [maas.wait_for_machine_status]
2019-06-02 07:34:42,271 [salt.utils.decorators:613 ][WARNING ][14797] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 07:34:45,006 [salt.state       :300 ][INFO    ][14797] {'ret': True}
2019-06-02 07:34:45,007 [salt.state       :1951][INFO    ][14797] Completed state [maas.wait_for_machine_status] at time 07:34:45.007234 duration_in_ms=2737.115
2019-06-02 07:34:45,011 [salt.minion      :1711][INFO    ][14797] Returning information for job: 20190602073434296290
2019-06-02 07:34:45,805 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602073445749253
2019-06-02 07:34:45,831 [salt.minion      :1432][INFO    ][14823] Starting a new job with PID 14823
2019-06-02 07:34:46,792 [salt.state       :915 ][INFO    ][14823] Loading fresh modules for state activity
2019-06-02 07:34:46,940 [salt.state       :1780][INFO    ][14823] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:34:46.940655
2019-06-02 07:34:46,941 [salt.state       :1813][INFO    ][14823] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 07:34:46,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14823] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 07:34:48,767 [salt.state       :300 ][INFO    ][14823] {'pid': 14830, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 07:34:48,769 [salt.state       :1951][INFO    ][14823] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:34:48.768831 duration_in_ms=1828.176
2019-06-02 07:34:48,773 [salt.state       :1780][INFO    ][14823] Running state [maas_machines_storage_cmp002_lvm] at time 07:34:48.772990
2019-06-02 07:34:48,773 [salt.state       :1813][INFO    ][14823] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-02 07:34:49,347 [salt.state       :300 ][INFO    ][14823] Machine cmp002 is not in Ready state.
2019-06-02 07:34:49,348 [salt.state       :1951][INFO    ][14823] Completed state [maas_machines_storage_cmp002_lvm] at time 07:34:49.348277 duration_in_ms=575.286
2019-06-02 07:34:49,348 [salt.state       :1780][INFO    ][14823] Running state [maas_machines_storage_cmp001_lvm] at time 07:34:49.348767
2019-06-02 07:34:49,349 [salt.state       :1813][INFO    ][14823] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-02 07:34:50,004 [salt.state       :300 ][INFO    ][14823] Machine cmp001 is not in Ready state.
2019-06-02 07:34:50,005 [salt.state       :1951][INFO    ][14823] Completed state [maas_machines_storage_cmp001_lvm] at time 07:34:50.005266 duration_in_ms=656.498
2019-06-02 07:34:50,010 [salt.minion      :1711][INFO    ][14823] Returning information for job: 20190602073445749253
2019-06-02 07:34:50,730 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602073450717401
2019-06-02 07:34:50,754 [salt.minion      :1432][INFO    ][14840] Starting a new job with PID 14840
2019-06-02 07:34:51,737 [salt.state       :915 ][INFO    ][14840] Loading fresh modules for state activity
2019-06-02 07:34:51,850 [salt.state       :1780][INFO    ][14840] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:34:51.849929
2019-06-02 07:34:51,850 [salt.state       :1813][INFO    ][14840] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 07:34:51,854 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14840] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 07:34:53,753 [salt.state       :300 ][INFO    ][14840] {'pid': 14847, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 07:34:53,755 [salt.state       :1951][INFO    ][14840] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:34:53.755094 duration_in_ms=1905.166
2019-06-02 07:34:53,758 [salt.state       :1780][INFO    ][14840] Running state [maas.deploy_machines] at time 07:34:53.758811
2019-06-02 07:34:53,759 [salt.state       :1813][INFO    ][14840] Executing state module.run for [maas.deploy_machines]
2019-06-02 07:34:53,760 [salt.utils.decorators:613 ][WARNING ][14840] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 07:34:54,355 [salt.loaded.ext.module.maas:684 ][INFO    ][14840] deploymachines hwe_kernel=hwe-16.04 system_id=pa4ar3 distro_series=xenial
2019-06-02 07:34:56,807 [salt.state       :300 ][INFO    ][14840] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-02 07:34:56,808 [salt.state       :1951][INFO    ][14840] Completed state [maas.deploy_machines] at time 07:34:56.808398 duration_in_ms=3049.587
2019-06-02 07:34:56,815 [salt.minion      :1711][INFO    ][14840] Returning information for job: 20190602073450717401
2019-06-02 07:34:57,513 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command state.apply with jid 20190602073457503106
2019-06-02 07:34:57,541 [salt.minion      :1432][INFO    ][14912] Starting a new job with PID 14912
2019-06-02 07:35:03,439 [salt.state       :915 ][INFO    ][14912] Loading fresh modules for state activity
2019-06-02 07:35:03,542 [salt.state       :1780][INFO    ][14912] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:35:03.542545
2019-06-02 07:35:03,542 [salt.state       :1813][INFO    ][14912] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 07:35:03,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14912] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 07:35:05,404 [salt.state       :300 ][INFO    ][14912] {'pid': 14923, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 07:35:05,405 [salt.state       :1951][INFO    ][14912] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:35:05.405359 duration_in_ms=1862.814
2019-06-02 07:35:05,408 [salt.state       :1780][INFO    ][14912] Running state [maas.wait_for_machine_status] at time 07:35:05.408856
2019-06-02 07:35:05,409 [salt.state       :1813][INFO    ][14912] Executing state module.run for [maas.wait_for_machine_status]
2019-06-02 07:35:05,409 [salt.utils.decorators:613 ][WARNING ][14912] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 07:35:08,111 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.31372404s left)
2019-06-02 07:35:12,621 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073512609042
2019-06-02 07:35:12,647 [salt.minion      :1432][INFO    ][14949] Starting a new job with PID 14949
2019-06-02 07:35:12,679 [salt.minion      :1711][INFO    ][14949] Returning information for job: 20190602073512609042
2019-06-02 07:35:40,928 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.49697113s left)
2019-06-02 07:35:42,691 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073542679174
2019-06-02 07:35:42,717 [salt.minion      :1432][INFO    ][14999] Starting a new job with PID 14999
2019-06-02 07:35:42,747 [salt.minion      :1711][INFO    ][14999] Returning information for job: 20190602073542679174
2019-06-02 07:36:12,784 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073612768648
2019-06-02 07:36:12,811 [salt.minion      :1432][INFO    ][15035] Starting a new job with PID 15035
2019-06-02 07:36:12,840 [salt.minion      :1711][INFO    ][15035] Returning information for job: 20190602073612768648
2019-06-02 07:36:13,807 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.61762714s left)
2019-06-02 07:36:42,870 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073642857316
2019-06-02 07:36:42,893 [salt.minion      :1432][INFO    ][15095] Starting a new job with PID 15095
2019-06-02 07:36:42,920 [salt.minion      :1711][INFO    ][15095] Returning information for job: 20190602073642857316
2019-06-02 07:36:46,543 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.88229895s left)
2019-06-02 07:37:12,959 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073712944421
2019-06-02 07:37:12,984 [salt.minion      :1432][INFO    ][15125] Starting a new job with PID 15125
2019-06-02 07:37:13,016 [salt.minion      :1711][INFO    ][15125] Returning information for job: 20190602073712944421
2019-06-02 07:37:19,371 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.05409098s left)
2019-06-02 07:37:43,051 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073743034584
2019-06-02 07:37:43,085 [salt.minion      :1432][INFO    ][15177] Starting a new job with PID 15177
2019-06-02 07:37:43,122 [salt.minion      :1711][INFO    ][15177] Returning information for job: 20190602073743034584
2019-06-02 07:37:52,035 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.38943696s left)
2019-06-02 07:38:13,161 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073813143921
2019-06-02 07:38:13,193 [salt.minion      :1432][INFO    ][15215] Starting a new job with PID 15215
2019-06-02 07:38:13,223 [salt.minion      :1711][INFO    ][15215] Returning information for job: 20190602073813143921
2019-06-02 07:38:25,063 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.36228395s left)
2019-06-02 07:38:43,269 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073843252100
2019-06-02 07:38:43,301 [salt.minion      :1432][INFO    ][15263] Starting a new job with PID 15263
2019-06-02 07:38:43,331 [salt.minion      :1711][INFO    ][15263] Returning information for job: 20190602073843252100
2019-06-02 07:38:57,837 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.58819199s left)
2019-06-02 07:39:13,353 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073913344080
2019-06-02 07:39:13,377 [salt.minion      :1432][INFO    ][15316] Starting a new job with PID 15316
2019-06-02 07:39:13,404 [salt.minion      :1711][INFO    ][15316] Returning information for job: 20190602073913344080
2019-06-02 07:39:30,804 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.62129307s left)
2019-06-02 07:39:43,470 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602073943453203
2019-06-02 07:39:43,498 [salt.minion      :1432][INFO    ][15376] Starting a new job with PID 15376
2019-06-02 07:39:43,532 [salt.minion      :1711][INFO    ][15376] Returning information for job: 20190602073943453203
2019-06-02 07:40:03,528 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.89661002s left)
2019-06-02 07:40:13,584 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074013569488
2019-06-02 07:40:13,611 [salt.minion      :1432][INFO    ][15425] Starting a new job with PID 15425
2019-06-02 07:40:13,641 [salt.minion      :1711][INFO    ][15425] Returning information for job: 20190602074013569488
2019-06-02 07:40:36,604 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.82112312s left)
2019-06-02 07:40:43,715 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074043702773
2019-06-02 07:40:43,746 [salt.minion      :1432][INFO    ][15582] Starting a new job with PID 15582
2019-06-02 07:40:43,777 [salt.minion      :1711][INFO    ][15582] Returning information for job: 20190602074043702773
2019-06-02 07:41:09,457 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.96815395s left)
2019-06-02 07:41:13,833 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074113822279
2019-06-02 07:41:13,859 [salt.minion      :1432][INFO    ][15613] Starting a new job with PID 15613
2019-06-02 07:41:13,891 [salt.minion      :1711][INFO    ][15613] Returning information for job: 20190602074113822279
2019-06-02 07:41:42,304 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.12048006s left)
2019-06-02 07:41:43,968 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074143952961
2019-06-02 07:41:43,998 [salt.minion      :1432][INFO    ][15684] Starting a new job with PID 15684
2019-06-02 07:41:44,027 [salt.minion      :1711][INFO    ][15684] Returning information for job: 20190602074143952961
2019-06-02 07:42:14,094 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074214084658
2019-06-02 07:42:14,112 [salt.minion      :1432][INFO    ][15715] Starting a new job with PID 15715
2019-06-02 07:42:14,142 [salt.minion      :1711][INFO    ][15715] Returning information for job: 20190602074214084658
2019-06-02 07:42:15,165 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1820.25993514s left)
2019-06-02 07:42:44,253 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074244237538
2019-06-02 07:42:44,280 [salt.minion      :1432][INFO    ][15881] Starting a new job with PID 15881
2019-06-02 07:42:44,317 [salt.minion      :1711][INFO    ][15881] Returning information for job: 20190602074244237538
2019-06-02 07:42:48,192 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.23313594s left)
2019-06-02 07:43:14,395 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074314380925
2019-06-02 07:43:14,422 [salt.minion      :1432][INFO    ][15912] Starting a new job with PID 15912
2019-06-02 07:43:14,455 [salt.minion      :1711][INFO    ][15912] Returning information for job: 20190602074314380925
2019-06-02 07:43:21,101 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.32381201s left)
2019-06-02 07:43:44,557 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074344539616
2019-06-02 07:43:44,588 [salt.minion      :1432][INFO    ][16028] Starting a new job with PID 16028
2019-06-02 07:43:44,628 [salt.minion      :1711][INFO    ][16028] Returning information for job: 20190602074344539616
2019-06-02 07:43:53,924 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.50082994s left)
2019-06-02 07:44:14,736 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074414722347
2019-06-02 07:44:14,768 [salt.minion      :1432][INFO    ][16058] Starting a new job with PID 16058
2019-06-02 07:44:14,806 [salt.minion      :1711][INFO    ][16058] Returning information for job: 20190602074414722347
2019-06-02 07:44:26,888 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1688.53719091s left)
2019-06-02 07:44:44,912 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074444895824
2019-06-02 07:44:44,941 [salt.minion      :1432][INFO    ][16104] Starting a new job with PID 16104
2019-06-02 07:44:44,973 [salt.minion      :1711][INFO    ][16104] Returning information for job: 20190602074444895824
2019-06-02 07:44:59,874 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1655.55070806s left)
2019-06-02 07:45:15,101 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074515086563
2019-06-02 07:45:15,127 [salt.minion      :1432][INFO    ][16136] Starting a new job with PID 16136
2019-06-02 07:45:15,161 [salt.minion      :1711][INFO    ][16136] Returning information for job: 20190602074515086563
2019-06-02 07:45:32,592 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1622.83263993s left)
2019-06-02 07:45:45,274 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074545258878
2019-06-02 07:45:45,305 [salt.minion      :1432][INFO    ][16186] Starting a new job with PID 16186
2019-06-02 07:45:45,337 [salt.minion      :1711][INFO    ][16186] Returning information for job: 20190602074545258878
2019-06-02 07:46:05,603 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1589.82187891s left)
2019-06-02 07:46:15,477 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074615460886
2019-06-02 07:46:15,508 [salt.minion      :1432][INFO    ][16224] Starting a new job with PID 16224
2019-06-02 07:46:15,539 [salt.minion      :1711][INFO    ][16224] Returning information for job: 20190602074615460886
2019-06-02 07:46:38,428 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.99660611s left)
2019-06-02 07:46:45,665 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074645648191
2019-06-02 07:46:45,694 [salt.minion      :1432][INFO    ][16432] Starting a new job with PID 16432
2019-06-02 07:46:45,729 [salt.minion      :1711][INFO    ][16432] Returning information for job: 20190602074645648191
2019-06-02 07:47:11,324 [salt.loaded.ext.module.maas:1023][INFO    ][14912] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1524.10086513s left)
2019-06-02 07:47:15,875 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602074715862705
2019-06-02 07:47:15,899 [salt.minion      :1432][INFO    ][16499] Starting a new job with PID 16499
2019-06-02 07:47:15,944 [salt.minion      :1711][INFO    ][16499] Returning information for job: 20190602074715862705
2019-06-02 07:47:31,605 [salt.utils.schedule:1377][INFO    ][6034] Running scheduled job: __mine_interval
2019-06-02 07:47:44,196 [salt.state       :300 ][INFO    ][14912] {'ret': True}
2019-06-02 07:47:44,197 [salt.state       :1951][INFO    ][14912] Completed state [maas.wait_for_machine_status] at time 07:47:44.197074 duration_in_ms=758788.216
2019-06-02 07:47:44,203 [salt.minion      :1711][INFO    ][14912] Returning information for job: 20190602073457503106
2019-06-02 08:47:31,605 [salt.utils.schedule:1377][INFO    ][6034] Running scheduled job: __mine_interval
2019-06-02 09:12:35,909 [salt.minion      :1308][INFO    ][6034] User sudo_ubuntu Executing command cp.push_dir with jid 20190602091235898780
2019-06-02 09:12:35,940 [salt.minion      :1432][INFO    ][22485] Starting a new job with PID 22485
