2019-02-22 13:14:32,838 [salt.utils.decorators:613 ][WARNING ][1849] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:14:33,597 [salt.utils.decorators:613 ][WARNING ][1849] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:14:36,081 [salt.loaded.int.states.file:2298][WARNING ][2317] 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-02-22 13:15:08,435 [salt.state       :2022][WARNING ][2901] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-22 13:15:11,494 [salt.utils.decorators:613 ][WARNING ][2901] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:16:58,266 [salt.utils.decorators:613 ][WARNING ][2901] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:24:03,526 [salt.utils.decorators:613 ][WARNING ][2901] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:24:25,057 [salt.utils.decorators:613 ][WARNING ][2901] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:24:27,641 [salt.loaded.ext.module.maasng:1008][WARNING ][2901] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-02-22 13:24:27,641 [salt.loaded.ext.module.maasng:1011][WARNING ][2901] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-02-22 13:24:28,260 [salt.loaded.ext.module.maasng:1235][WARNING ][2901] Ignoring parameter vlan:0
2019-02-22 13:24:29,063 [salt.utils.decorators:613 ][WARNING ][2901] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:24:37,808 [salt.utils.decorators:613 ][WARNING ][16784] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:24:37,865 [salt.loaded.ext.module.maas:412 ][WARNING ][16784] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:24:39,046 [salt.loaded.ext.module.maas:412 ][WARNING ][16784] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:24:40,152 [salt.loaded.ext.module.maas:412 ][WARNING ][16784] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:24:41,370 [salt.loaded.ext.module.maas:412 ][WARNING ][16784] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:24:42,512 [salt.loaded.ext.module.maas:412 ][WARNING ][16784] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:24:48,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17419] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-22 13:24:48,365 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17419] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-22 13:24:48,415 [salt.utils.parsers:1051][WARNING ][273] Minion received a SIGTERM. Exiting.
2019-02-22 13:24:49,544 [salt.cli.daemons :293 ][INFO    ][17473] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-02-22 13:24:49,663 [salt.cli.daemons :82  ][INFO    ][17473] Starting up the Salt Minion
2019-02-22 13:24:49,663 [salt.utils.event :1017][INFO    ][17473] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-02-22 13:24:50,816 [salt.minion      :976 ][INFO    ][17473] Creating minion process manager
2019-02-22 13:24:52,429 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][17473] Executing command ['date', '+%z'] in directory '/root'
2019-02-22 13:24:52,453 [salt.utils.schedule:568 ][INFO    ][17473] Updating job settings for scheduled job: __mine_interval
2019-02-22 13:24:52,457 [salt.minion      :1108][INFO    ][17473] Added mine.update to scheduler
2019-02-22 13:24:52,462 [salt.minion      :1975][INFO    ][17473] Minion is starting as user 'root'
2019-02-22 13:24:52,478 [salt.minion      :2336][INFO    ][17473] Minion is ready to receive requests!
2019-02-22 13:25:14,669 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222132514656966
2019-02-22 13:25:14,686 [salt.minion      :1432][INFO    ][17604] Starting a new job with PID 17604
2019-02-22 13:25:20,432 [salt.state       :915 ][INFO    ][17604] Loading fresh modules for state activity
2019-02-22 13:25:20,490 [salt.fileclient  :1219][INFO    ][17604] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-22 13:25:20,537 [salt.state       :1780][INFO    ][17604] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:25:20.537899
2019-02-22 13:25:20,538 [salt.state       :1813][INFO    ][17604] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:25:20,540 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17604] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:25:22,209 [salt.state       :300 ][INFO    ][17604] {'pid': 17627, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:25:22,211 [salt.state       :1951][INFO    ][17604] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:25:22.211495 duration_in_ms=1673.597
2019-02-22 13:25:22,214 [salt.state       :1780][INFO    ][17604] Running state [maas.wait_for_machine_status] at time 13:25:22.214362
2019-02-22 13:25:22,216 [salt.state       :1813][INFO    ][17604] Executing state module.run for [maas.wait_for_machine_status]
2019-02-22 13:25:22,216 [salt.utils.decorators:613 ][WARNING ][17604] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:25:23,044 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.17981505s left)
2019-02-22 13:25:29,731 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132529721756
2019-02-22 13:25:29,746 [salt.minion      :1432][INFO    ][17646] Starting a new job with PID 17646
2019-02-22 13:25:29,769 [salt.minion      :1711][INFO    ][17646] Returning information for job: 20190222132529721756
2019-02-22 13:25:53,824 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.39970589s left)
2019-02-22 13:25:59,918 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132559904733
2019-02-22 13:25:59,938 [salt.minion      :1432][INFO    ][17691] Starting a new job with PID 17691
2019-02-22 13:25:59,957 [salt.minion      :1711][INFO    ][17691] Returning information for job: 20190222132559904733
2019-02-22 13:26:24,628 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.59540391s left)
2019-02-22 13:26:29,962 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132629952538
2019-02-22 13:26:29,984 [salt.minion      :1432][INFO    ][17746] Starting a new job with PID 17746
2019-02-22 13:26:30,007 [salt.minion      :1711][INFO    ][17746] Returning information for job: 20190222132629952538
2019-02-22 13:26:55,453 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.77036309s left)
2019-02-22 13:27:00,018 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132700007346
2019-02-22 13:27:00,035 [salt.minion      :1432][INFO    ][17790] Starting a new job with PID 17790
2019-02-22 13:27:00,056 [salt.minion      :1711][INFO    ][17790] Returning information for job: 20190222132700007346
2019-02-22 13:27:26,524 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.69924998s left)
2019-02-22 13:27:30,068 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132730054943
2019-02-22 13:27:30,087 [salt.minion      :1432][INFO    ][17874] Starting a new job with PID 17874
2019-02-22 13:27:30,107 [salt.minion      :1711][INFO    ][17874] Returning information for job: 20190222132730054943
2019-02-22 13:27:57,601 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.62264991s left)
2019-02-22 13:28:00,124 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132800108160
2019-02-22 13:28:00,144 [salt.minion      :1432][INFO    ][17988] Starting a new job with PID 17988
2019-02-22 13:28:00,166 [salt.minion      :1711][INFO    ][17988] Returning information for job: 20190222132800108160
2019-02-22 13:28:28,697 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.52677608s left)
2019-02-22 13:28:30,210 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132830196489
2019-02-22 13:28:30,225 [salt.minion      :1432][INFO    ][18208] Starting a new job with PID 18208
2019-02-22 13:28:30,247 [salt.minion      :1711][INFO    ][18208] Returning information for job: 20190222132830196489
2019-02-22 13:28:59,817 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.40627789s left)
2019-02-22 13:29:00,278 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132900266016
2019-02-22 13:29:00,294 [salt.minion      :1432][INFO    ][18407] Starting a new job with PID 18407
2019-02-22 13:29:00,313 [salt.minion      :1711][INFO    ][18407] Returning information for job: 20190222132900266016
2019-02-22 13:29:30,322 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132930312029
2019-02-22 13:29:30,338 [salt.minion      :1432][INFO    ][18574] Starting a new job with PID 18574
2019-02-22 13:29:30,360 [salt.minion      :1711][INFO    ][18574] Returning information for job: 20190222132930312029
2019-02-22 13:29:31,381 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.84209704s left)
2019-02-22 13:30:00,453 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133000441745
2019-02-22 13:30:00,474 [salt.minion      :1432][INFO    ][18801] Starting a new job with PID 18801
2019-02-22 13:30:00,496 [salt.minion      :1711][INFO    ][18801] Returning information for job: 20190222133000441745
2019-02-22 13:30:03,525 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.69845605s left)
2019-02-22 13:30:30,585 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133030575492
2019-02-22 13:30:30,606 [salt.minion      :1432][INFO    ][19225] Starting a new job with PID 19225
2019-02-22 13:30:30,626 [salt.minion      :1711][INFO    ][19225] Returning information for job: 20190222133030575492
2019-02-22 13:30:35,966 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1186.25786901s left)
2019-02-22 13:31:00,684 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133100670946
2019-02-22 13:31:00,703 [salt.minion      :1432][INFO    ][19345] Starting a new job with PID 19345
2019-02-22 13:31:00,727 [salt.minion      :1711][INFO    ][19345] Returning information for job: 20190222133100670946
2019-02-22 13:31:08,277 [salt.loaded.ext.module.maas:1023][INFO    ][17604] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:1500s (1153.94614601s left)
2019-02-22 13:31:30,776 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133130768671
2019-02-22 13:31:30,789 [salt.minion      :1432][INFO    ][19488] Starting a new job with PID 19488
2019-02-22 13:31:30,808 [salt.minion      :1711][INFO    ][19488] Returning information for job: 20190222133130768671
2019-02-22 13:31:41,305 [salt.state       :300 ][INFO    ][17604] {'ret': True}
2019-02-22 13:31:41,306 [salt.state       :1951][INFO    ][17604] Completed state [maas.wait_for_machine_status] at time 13:31:41.306051 duration_in_ms=379091.688
2019-02-22 13:31:41,310 [salt.minion      :1711][INFO    ][17604] Returning information for job: 20190222132514656966
2019-02-22 13:31:41,898 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222133141884684
2019-02-22 13:31:41,913 [salt.minion      :1432][INFO    ][19532] Starting a new job with PID 19532
2019-02-22 13:31:47,590 [salt.state       :915 ][INFO    ][19532] Loading fresh modules for state activity
2019-02-22 13:31:47,645 [salt.fileclient  :1219][INFO    ][19532] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-22 13:31:47,740 [salt.state       :1780][INFO    ][19532] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:31:47.740056
2019-02-22 13:31:47,740 [salt.state       :1813][INFO    ][19532] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:31:47,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19532] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:31:49,495 [salt.state       :300 ][INFO    ][19532] {'pid': 19541, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:31:49,496 [salt.state       :1951][INFO    ][19532] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:31:49.496591 duration_in_ms=1756.534
2019-02-22 13:31:49,501 [salt.state       :1780][INFO    ][19532] Running state [maas_machines_storage_cmp002_lvm] at time 13:31:49.500950
2019-02-22 13:31:49,501 [salt.state       :1813][INFO    ][19532] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-22 13:31:50,598 [salt.loaded.ext.module.maasng:610 ][INFO    ][19532] taw43g
2019-02-22 13:31:50,599 [salt.loaded.ext.module.maasng:626 ][INFO    ][19532] sda
2019-02-22 13:31:51,155 [salt.loaded.ext.module.maasng:361 ][INFO    ][19532] taw43g
2019-02-22 13:31:51,243 [salt.loaded.ext.module.maasng:367 ][INFO    ][19532] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'47fba20a-e97c-4305-8f8c-b44eb37b3e3e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'taw43g', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'72594b25-5933-428c-88ee-cf79e6594780'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/blockdevices/1/partition/1'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'taw43g', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/blockdevices/1/'}, {u'size': 800097042432, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'53ded7e8-c534-4f39-9461-b03ae642ee36', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'taw43g', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'ca3f550b-fee1-4d90-bf00-11cdd4339ea5'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'type': u'virtual', u'id': 3, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/blockdevices/3/'}]
2019-02-22 13:31:51,244 [salt.loaded.ext.module.maasng:632 ][INFO    ][19532] vgroot
2019-02-22 13:31:51,244 [salt.loaded.ext.module.maasng:635 ][INFO    ][19532] lvroot
2019-02-22 13:31:51,244 [salt.loaded.ext.module.maasng:639 ][INFO    ][19532] 107374182400
2019-02-22 13:31:51,839 [salt.loaded.ext.module.maasng:645 ][INFO    ][19532] {u'hwe_kernel': u'', u'status_action': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'taw43g', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'45cc9706-b302-45aa-848b-0ab63570d1b8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'taw43g', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'065b8d4e-29a7-4f52-ba4d-54cfeee73c4c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/blockdevices/1/partition/6'}], u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/taw43g/', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'taw43g', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'45cc9706-b302-45aa-848b-0ab63570d1b8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'taw43g', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'065b8d4e-29a7-4f52-ba4d-54cfeee73c4c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'taw43g', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'used_size': 800106479616, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/blockdevices/1/'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'98d1c50e-3614-41e5-8bd4-a460c647ef52', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'taw43g', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f4ca730b-edbe-4fa1-9c4d-82ca13116a64', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'used_size': 107374182400, u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/blockdevices/11/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'taw43g', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'45cc9706-b302-45aa-848b-0ab63570d1b8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'taw43g', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'065b8d4e-29a7-4f52-ba4d-54cfeee73c4c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/blockdevices/1/partition/6'}], u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'taw43g', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/blockdevices/11/', u'available_size': 0, u'uuid': u'98d1c50e-3614-41e5-8bd4-a460c647ef52', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'taw43g', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f4ca730b-edbe-4fa1-9c4d-82ca13116a64', u'fstype': u'ext4'}, u'block_size': 4096, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'system_id': u'taw43g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/interfaces/4/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'system_id': u'taw43g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'system_id': u'taw43g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/interfaces/11/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'system_id': u'taw43g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'system_id': u'taw43g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/interfaces/12/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'system_id': u'taw43g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/interfaces/13/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 14, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'system_id': u'taw43g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/taw43g/interfaces/14/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-02-22 13:31:51,841 [salt.state       :300 ][INFO    ][19532] {'new': {'storage_layout': 'lvm'}}
2019-02-22 13:31:51,842 [salt.state       :1951][INFO    ][19532] Completed state [maas_machines_storage_cmp002_lvm] at time 13:31:51.842063 duration_in_ms=2341.113
2019-02-22 13:31:51,842 [salt.state       :1780][INFO    ][19532] Running state [maas_machines_storage_cmp001_lvm] at time 13:31:51.842625
2019-02-22 13:31:51,844 [salt.state       :1813][INFO    ][19532] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-22 13:31:52,992 [salt.loaded.ext.module.maasng:610 ][INFO    ][19532] n7thfx
2019-02-22 13:31:52,992 [salt.loaded.ext.module.maasng:626 ][INFO    ][19532] sda
2019-02-22 13:31:53,539 [salt.loaded.ext.module.maasng:361 ][INFO    ][19532] n7thfx
2019-02-22 13:31:53,620 [salt.loaded.ext.module.maasng:367 ][INFO    ][19532] [{u'size': 800109715456, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/blockdevices/2/', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'c7f25420-2203-45d7-b9d8-42d5c4833c34', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n7thfx', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'267209ee-5a2a-47e9-ad4e-719ad6e3d165', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/blockdevices/2/partition/2'}], u'tags': [u'ssd'], u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'n7thfx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, {u'size': 800097042432, u'uuid': u'06a4bfa4-d45b-4358-b04e-e75e94f6aec2', u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/blockdevices/4/', u'type': u'virtual', u'partitions': [], u'tags': [], u'id': 4, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'n7thfx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'744c23bd-c9f6-4360-99b8-0c644a104f2b', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-02-22 13:31:53,620 [salt.loaded.ext.module.maasng:632 ][INFO    ][19532] vgroot
2019-02-22 13:31:53,621 [salt.loaded.ext.module.maasng:635 ][INFO    ][19532] lvroot
2019-02-22 13:31:53,621 [salt.loaded.ext.module.maasng:639 ][INFO    ][19532] 107374182400
2019-02-22 13:31:54,204 [salt.loaded.ext.module.maasng:645 ][INFO    ][19532] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'status_action': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'b90d8bba-e603-4280-8e9a-956ec313d38f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n7thfx', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'b3782b40-cae8-4f99-b5eb-9d64c11093ec'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n7thfx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/blockdevices/2/'}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'b90d8bba-e603-4280-8e9a-956ec313d38f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n7thfx', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'b3782b40-cae8-4f99-b5eb-9d64c11093ec'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n7thfx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/blockdevices/2/'}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'663995f4-f063-427c-befb-3f85f81605a3', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'n7thfx', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'6bdb365b-6f65-49f2-8d89-5c1e06e1d43e'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/blockdevices/12/'}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'n7thfx', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'663995f4-f063-427c-befb-3f85f81605a3', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'n7thfx', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'6bdb365b-6f65-49f2-8d89-5c1e06e1d43e'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/blockdevices/12/'}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'n7thfx', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n7thfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/interfaces/5/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 18, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n7thfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/interfaces/18/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 19, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n7thfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/interfaces/19/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 16, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n7thfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/interfaces/16/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 20, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n7thfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 17, u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n7thfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/interfaces/17/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'b90d8bba-e603-4280-8e9a-956ec313d38f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n7thfx', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'b3782b40-cae8-4f99-b5eb-9d64c11093ec'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'n7thfx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n7thfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n7thfx/interfaces/5/'}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/n7thfx/', u'distro_series': u'', u'memory_test_status': -1}
2019-02-22 13:31:54,206 [salt.state       :300 ][INFO    ][19532] {'new': {'storage_layout': 'lvm'}}
2019-02-22 13:31:54,208 [salt.state       :1951][INFO    ][19532] Completed state [maas_machines_storage_cmp001_lvm] at time 13:31:54.206814 duration_in_ms=2364.188
2019-02-22 13:31:54,210 [salt.minion      :1711][INFO    ][19532] Returning information for job: 20190222133141884684
2019-02-22 13:31:54,744 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222133154732634
2019-02-22 13:31:54,763 [salt.minion      :1432][INFO    ][19579] Starting a new job with PID 19579
2019-02-22 13:31:55,460 [salt.state       :915 ][INFO    ][19579] Loading fresh modules for state activity
2019-02-22 13:31:55,508 [salt.fileclient  :1219][INFO    ][19579] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-22 13:31:55,548 [salt.state       :1780][INFO    ][19579] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:31:55.548905
2019-02-22 13:31:55,549 [salt.state       :1813][INFO    ][19579] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:31:55,551 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19579] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:31:57,257 [salt.state       :300 ][INFO    ][19579] {'pid': 19586, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:31:57,258 [salt.state       :1951][INFO    ][19579] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:31:57.257967 duration_in_ms=1709.061
2019-02-22 13:31:57,261 [salt.state       :1780][INFO    ][19579] Running state [maas.deploy_machines] at time 13:31:57.261280
2019-02-22 13:31:57,262 [salt.state       :1813][INFO    ][19579] Executing state module.run for [maas.deploy_machines]
2019-02-22 13:31:57,263 [salt.utils.decorators:613 ][WARNING ][19579] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:31:57,835 [salt.loaded.ext.module.maas:684 ][INFO    ][19579] deploymachines hwe_kernel=hwe-16.04 system_id=taw43g distro_series=xenial
2019-02-22 13:32:00,228 [salt.loaded.ext.module.maas:684 ][INFO    ][19579] deploymachines hwe_kernel=hwe-16.04 system_id=n7thfx distro_series=xenial
2019-02-22 13:32:02,573 [salt.loaded.ext.module.maas:684 ][INFO    ][19579] deploymachines hwe_kernel=hwe-16.04 system_id=nm8aet distro_series=xenial
2019-02-22 13:32:04,878 [salt.loaded.ext.module.maas:684 ][INFO    ][19579] deploymachines hwe_kernel=hwe-16.04 system_id=dkx8nr distro_series=xenial
2019-02-22 13:32:07,202 [salt.loaded.ext.module.maas:684 ][INFO    ][19579] deploymachines hwe_kernel=hwe-16.04 system_id=mqyp6q distro_series=xenial
2019-02-22 13:32:09,516 [salt.state       :300 ][INFO    ][19579] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-22 13:32:09,517 [salt.state       :1951][INFO    ][19579] Completed state [maas.deploy_machines] at time 13:32:09.517283 duration_in_ms=12256.002
2019-02-22 13:32:09,523 [salt.minion      :1711][INFO    ][19579] Returning information for job: 20190222133154732634
2019-02-22 13:32:10,062 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222133210053560
2019-02-22 13:32:10,077 [salt.minion      :1432][INFO    ][19864] Starting a new job with PID 19864
2019-02-22 13:32:15,913 [salt.state       :915 ][INFO    ][19864] Loading fresh modules for state activity
2019-02-22 13:32:15,965 [salt.fileclient  :1219][INFO    ][19864] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-22 13:32:16,011 [salt.state       :1780][INFO    ][19864] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:32:16.011512
2019-02-22 13:32:16,011 [salt.state       :1813][INFO    ][19864] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:32:16,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19864] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:32:17,709 [salt.state       :300 ][INFO    ][19864] {'pid': 19908, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:32:17,710 [salt.state       :1951][INFO    ][19864] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:32:17.710779 duration_in_ms=1699.267
2019-02-22 13:32:17,713 [salt.state       :1780][INFO    ][19864] Running state [maas.wait_for_machine_status] at time 13:32:17.713454
2019-02-22 13:32:17,714 [salt.state       :1813][INFO    ][19864] Executing state module.run for [maas.wait_for_machine_status]
2019-02-22 13:32:17,716 [salt.utils.decorators:613 ][WARNING ][19864] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:32:20,584 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.13889289s left)
2019-02-22 13:32:25,101 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133225085153
2019-02-22 13:32:25,122 [salt.minion      :1432][INFO    ][19937] Starting a new job with PID 19937
2019-02-22 13:32:25,141 [salt.minion      :1711][INFO    ][19937] Returning information for job: 20190222133225085153
2019-02-22 13:32:53,286 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.43739295s left)
2019-02-22 13:32:55,144 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133255128152
2019-02-22 13:32:55,161 [salt.minion      :1432][INFO    ][19975] Starting a new job with PID 19975
2019-02-22 13:32:55,181 [salt.minion      :1711][INFO    ][19975] Returning information for job: 20190222133255128152
2019-02-22 13:33:25,194 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133325186109
2019-02-22 13:33:25,208 [salt.minion      :1432][INFO    ][20036] Starting a new job with PID 20036
2019-02-22 13:33:25,226 [salt.minion      :1711][INFO    ][20036] Returning information for job: 20190222133325186109
2019-02-22 13:33:26,071 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.65201402s left)
2019-02-22 13:33:55,236 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133355221770
2019-02-22 13:33:55,255 [salt.minion      :1432][INFO    ][20075] Starting a new job with PID 20075
2019-02-22 13:33:55,276 [salt.minion      :1711][INFO    ][20075] Returning information for job: 20190222133355221770
2019-02-22 13:33:58,901 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.82262993s left)
2019-02-22 13:34:25,290 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133425278228
2019-02-22 13:34:25,307 [salt.minion      :1432][INFO    ][20138] Starting a new job with PID 20138
2019-02-22 13:34:25,328 [salt.minion      :1711][INFO    ][20138] Returning information for job: 20190222133425278228
2019-02-22 13:34:31,783 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.94077587s left)
2019-02-22 13:34:55,320 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133455310562
2019-02-22 13:34:55,341 [salt.minion      :1432][INFO    ][20194] Starting a new job with PID 20194
2019-02-22 13:34:55,367 [salt.minion      :1711][INFO    ][20194] Returning information for job: 20190222133455310562
2019-02-22 13:35:04,650 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2083.07361507s left)
2019-02-22 13:35:25,394 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133525381380
2019-02-22 13:35:25,414 [salt.minion      :1432][INFO    ][20363] Starting a new job with PID 20363
2019-02-22 13:35:25,438 [salt.minion      :1711][INFO    ][20363] Returning information for job: 20190222133525381380
2019-02-22 13:35:37,498 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.22566605s left)
2019-02-22 13:35:55,464 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133555457517
2019-02-22 13:35:55,476 [salt.minion      :1432][INFO    ][20472] Starting a new job with PID 20472
2019-02-22 13:35:55,495 [salt.minion      :1711][INFO    ][20472] Returning information for job: 20190222133555457517
2019-02-22 13:36:10,322 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.40118599s left)
2019-02-22 13:36:25,548 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133625541993
2019-02-22 13:36:25,560 [salt.minion      :1432][INFO    ][20768] Starting a new job with PID 20768
2019-02-22 13:36:25,580 [salt.minion      :1711][INFO    ][20768] Returning information for job: 20190222133625541993
2019-02-22 13:36:43,692 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1984.03092194s left)
2019-02-22 13:36:55,592 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133655580859
2019-02-22 13:36:55,610 [salt.minion      :1432][INFO    ][20877] Starting a new job with PID 20877
2019-02-22 13:36:55,641 [salt.minion      :1711][INFO    ][20877] Returning information for job: 20190222133655580859
2019-02-22 13:37:16,481 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.24256992s left)
2019-02-22 13:37:25,705 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133725691198
2019-02-22 13:37:25,724 [salt.minion      :1432][INFO    ][21071] Starting a new job with PID 21071
2019-02-22 13:37:25,752 [salt.minion      :1711][INFO    ][21071] Returning information for job: 20190222133725691198
2019-02-22 13:37:49,487 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.23591304s left)
2019-02-22 13:37:55,801 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133755793621
2019-02-22 13:37:55,814 [salt.minion      :1432][INFO    ][21211] Starting a new job with PID 21211
2019-02-22 13:37:55,844 [salt.minion      :1711][INFO    ][21211] Returning information for job: 20190222133755793621
2019-02-22 13:38:22,639 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1885.08421087s left)
2019-02-22 13:38:25,896 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133825889077
2019-02-22 13:38:25,913 [salt.minion      :1432][INFO    ][21499] Starting a new job with PID 21499
2019-02-22 13:38:25,939 [salt.minion      :1711][INFO    ][21499] Returning information for job: 20190222133825889077
2019-02-22 13:38:55,573 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.15009403s left)
2019-02-22 13:38:55,996 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133855980645
2019-02-22 13:38:56,014 [salt.minion      :1432][INFO    ][21649] Starting a new job with PID 21649
2019-02-22 13:38:56,041 [salt.minion      :1711][INFO    ][21649] Returning information for job: 20190222133855980645
2019-02-22 13:39:26,111 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133926102791
2019-02-22 13:39:26,130 [salt.minion      :1432][INFO    ][21891] Starting a new job with PID 21891
2019-02-22 13:39:26,158 [salt.minion      :1711][INFO    ][21891] Returning information for job: 20190222133926102791
2019-02-22 13:39:28,445 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1819.2783339s left)
2019-02-22 13:39:56,234 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133956220734
2019-02-22 13:39:56,254 [salt.minion      :1432][INFO    ][21953] Starting a new job with PID 21953
2019-02-22 13:39:56,282 [salt.minion      :1711][INFO    ][21953] Returning information for job: 20190222133956220734
2019-02-22 13:40:01,417 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1786.30591488s left)
2019-02-22 13:40:26,385 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134026374802
2019-02-22 13:40:26,409 [salt.minion      :1432][INFO    ][22311] Starting a new job with PID 22311
2019-02-22 13:40:26,440 [salt.minion      :1711][INFO    ][22311] Returning information for job: 20190222134026374802
2019-02-22 13:40:34,186 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.53750706s left)
2019-02-22 13:40:56,549 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134056534523
2019-02-22 13:40:56,566 [salt.minion      :1432][INFO    ][22414] Starting a new job with PID 22414
2019-02-22 13:40:56,593 [salt.minion      :1711][INFO    ][22414] Returning information for job: 20190222134056534523
2019-02-22 13:41:07,161 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.56238794s left)
2019-02-22 13:41:26,606 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134126597755
2019-02-22 13:41:26,622 [salt.minion      :1432][INFO    ][22539] Starting a new job with PID 22539
2019-02-22 13:41:26,645 [salt.minion      :1711][INFO    ][22539] Returning information for job: 20190222134126597755
2019-02-22 13:41:40,069 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1687.65420389s left)
2019-02-22 13:41:56,684 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134156671534
2019-02-22 13:41:56,705 [salt.minion      :1432][INFO    ][22649] Starting a new job with PID 22649
2019-02-22 13:41:56,731 [salt.minion      :1711][INFO    ][22649] Returning information for job: 20190222134156671534
2019-02-22 13:42:13,653 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1654.07009506s left)
2019-02-22 13:42:26,865 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134226852788
2019-02-22 13:42:26,880 [salt.minion      :1432][INFO    ][22862] Starting a new job with PID 22862
2019-02-22 13:42:26,908 [salt.minion      :1711][INFO    ][22862] Returning information for job: 20190222134226852788
2019-02-22 13:42:46,438 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1621.28566694s left)
2019-02-22 13:42:56,996 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134256986214
2019-02-22 13:42:57,018 [salt.minion      :1432][INFO    ][22916] Starting a new job with PID 22916
2019-02-22 13:42:57,044 [salt.minion      :1711][INFO    ][22916] Returning information for job: 20190222134256986214
2019-02-22 13:43:19,283 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.44000888s left)
2019-02-22 13:43:27,165 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134327156455
2019-02-22 13:43:27,186 [salt.minion      :1432][INFO    ][22979] Starting a new job with PID 22979
2019-02-22 13:43:27,211 [salt.minion      :1711][INFO    ][22979] Returning information for job: 20190222134327156455
2019-02-22 13:43:52,162 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1555.56096196s left)
2019-02-22 13:43:57,305 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134357294144
2019-02-22 13:43:57,325 [salt.minion      :1432][INFO    ][23018] Starting a new job with PID 23018
2019-02-22 13:43:57,353 [salt.minion      :1711][INFO    ][23018] Returning information for job: 20190222134357294144
2019-02-22 13:44:25,067 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1522.65634394s left)
2019-02-22 13:44:27,494 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134427488008
2019-02-22 13:44:27,514 [salt.minion      :1432][INFO    ][23131] Starting a new job with PID 23131
2019-02-22 13:44:27,545 [salt.minion      :1711][INFO    ][23131] Returning information for job: 20190222134427488008
2019-02-22 13:44:57,647 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134457637748
2019-02-22 13:44:57,666 [salt.minion      :1432][INFO    ][23246] Starting a new job with PID 23246
2019-02-22 13:44:57,692 [salt.minion      :1711][INFO    ][23246] Returning information for job: 20190222134457637748
2019-02-22 13:44:57,909 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1489.81396484s left)
2019-02-22 13:45:27,870 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134527861371
2019-02-22 13:45:27,893 [salt.minion      :1432][INFO    ][23444] Starting a new job with PID 23444
2019-02-22 13:45:27,921 [salt.minion      :1711][INFO    ][23444] Returning information for job: 20190222134527861371
2019-02-22 13:45:30,695 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1457.02865505s left)
2019-02-22 13:45:58,056 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134558045293
2019-02-22 13:45:58,079 [salt.minion      :1432][INFO    ][23524] Starting a new job with PID 23524
2019-02-22 13:45:58,109 [salt.minion      :1711][INFO    ][23524] Returning information for job: 20190222134558045293
2019-02-22 13:46:03,440 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1424.28320503s left)
2019-02-22 13:46:28,073 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134628061136
2019-02-22 13:46:28,089 [salt.minion      :1432][INFO    ][23591] Starting a new job with PID 23591
2019-02-22 13:46:28,116 [salt.minion      :1711][INFO    ][23591] Returning information for job: 20190222134628061136
2019-02-22 13:46:36,196 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1391.52694488s left)
2019-02-22 13:46:58,279 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134658269108
2019-02-22 13:46:58,294 [salt.minion      :1432][INFO    ][23631] Starting a new job with PID 23631
2019-02-22 13:46:58,322 [salt.minion      :1711][INFO    ][23631] Returning information for job: 20190222134658269108
2019-02-22 13:47:09,057 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1358.66651607s left)
2019-02-22 13:47:28,315 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134728303427
2019-02-22 13:47:28,332 [salt.minion      :1432][INFO    ][23699] Starting a new job with PID 23699
2019-02-22 13:47:28,364 [salt.minion      :1711][INFO    ][23699] Returning information for job: 20190222134728303427
2019-02-22 13:47:41,902 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1325.82144284s left)
2019-02-22 13:47:58,369 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134758353235
2019-02-22 13:47:58,394 [salt.minion      :1432][INFO    ][23743] Starting a new job with PID 23743
2019-02-22 13:47:58,448 [salt.minion      :1711][INFO    ][23743] Returning information for job: 20190222134758353235
2019-02-22 13:48:14,968 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1292.75483298s left)
2019-02-22 13:48:28,434 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134828422319
2019-02-22 13:48:28,449 [salt.minion      :1432][INFO    ][23815] Starting a new job with PID 23815
2019-02-22 13:48:28,482 [salt.minion      :1711][INFO    ][23815] Returning information for job: 20190222134828422319
2019-02-22 13:48:47,748 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1259.97478795s left)
2019-02-22 13:48:58,494 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134858483769
2019-02-22 13:48:58,512 [salt.minion      :1432][INFO    ][23862] Starting a new job with PID 23862
2019-02-22 13:48:58,537 [salt.minion      :1711][INFO    ][23862] Returning information for job: 20190222134858483769
2019-02-22 13:49:20,329 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1227.39380789s left)
2019-02-22 13:49:28,540 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134928526756
2019-02-22 13:49:28,562 [salt.minion      :1432][INFO    ][23923] Starting a new job with PID 23923
2019-02-22 13:49:28,595 [salt.minion      :1711][INFO    ][23923] Returning information for job: 20190222134928526756
2019-02-22 13:49:53,035 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1194.68845391s left)
2019-02-22 13:49:58,725 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134958716421
2019-02-22 13:49:58,745 [salt.minion      :1432][INFO    ][23969] Starting a new job with PID 23969
2019-02-22 13:49:58,773 [salt.minion      :1711][INFO    ][23969] Returning information for job: 20190222134958716421
2019-02-22 13:50:25,693 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1162.03026485s left)
2019-02-22 13:50:28,796 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135028785048
2019-02-22 13:50:28,817 [salt.minion      :1432][INFO    ][24028] Starting a new job with PID 24028
2019-02-22 13:50:28,846 [salt.minion      :1711][INFO    ][24028] Returning information for job: 20190222135028785048
2019-02-22 13:50:58,593 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1129.130193s left)
2019-02-22 13:50:58,918 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135058907857
2019-02-22 13:50:58,938 [salt.minion      :1432][INFO    ][24072] Starting a new job with PID 24072
2019-02-22 13:50:58,967 [salt.minion      :1711][INFO    ][24072] Returning information for job: 20190222135058907857
2019-02-22 13:51:29,017 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135129009638
2019-02-22 13:51:29,034 [salt.minion      :1432][INFO    ][24125] Starting a new job with PID 24125
2019-02-22 13:51:29,060 [salt.minion      :1711][INFO    ][24125] Returning information for job: 20190222135129009638
2019-02-22 13:51:31,281 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1096.44210196s left)
2019-02-22 13:51:59,144 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135159131492
2019-02-22 13:51:59,168 [salt.minion      :1432][INFO    ][24170] Starting a new job with PID 24170
2019-02-22 13:51:59,195 [salt.minion      :1711][INFO    ][24170] Returning information for job: 20190222135159131492
2019-02-22 13:52:03,941 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1063.78182697s left)
2019-02-22 13:52:29,229 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135229219372
2019-02-22 13:52:29,254 [salt.minion      :1432][INFO    ][24229] Starting a new job with PID 24229
2019-02-22 13:52:29,281 [salt.minion      :1711][INFO    ][24229] Returning information for job: 20190222135229219372
2019-02-22 13:52:36,872 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1030.85162997s left)
2019-02-22 13:52:59,409 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135259397130
2019-02-22 13:52:59,430 [salt.minion      :1432][INFO    ][24275] Starting a new job with PID 24275
2019-02-22 13:52:59,458 [salt.minion      :1711][INFO    ][24275] Returning information for job: 20190222135259397130
2019-02-22 13:53:09,494 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (998.229454994s left)
2019-02-22 13:53:29,545 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135329538584
2019-02-22 13:53:29,558 [salt.minion      :1432][INFO    ][24334] Starting a new job with PID 24334
2019-02-22 13:53:29,588 [salt.minion      :1711][INFO    ][24334] Returning information for job: 20190222135329538584
2019-02-22 13:53:42,487 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (965.23603487s left)
2019-02-22 13:53:59,750 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135359736147
2019-02-22 13:53:59,772 [salt.minion      :1432][INFO    ][24378] Starting a new job with PID 24378
2019-02-22 13:53:59,799 [salt.minion      :1711][INFO    ][24378] Returning information for job: 20190222135359736147
2019-02-22 13:54:15,293 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (932.430685043s left)
2019-02-22 13:54:29,768 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135429753653
2019-02-22 13:54:29,790 [salt.minion      :1432][INFO    ][24439] Starting a new job with PID 24439
2019-02-22 13:54:29,820 [salt.minion      :1711][INFO    ][24439] Returning information for job: 20190222135429753653
2019-02-22 13:54:47,959 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (899.764378071s left)
2019-02-22 13:54:59,961 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135459946129
2019-02-22 13:54:59,984 [salt.minion      :1432][INFO    ][24489] Starting a new job with PID 24489
2019-02-22 13:55:00,014 [salt.minion      :1711][INFO    ][24489] Returning information for job: 20190222135459946129
2019-02-22 13:55:20,665 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (867.058081865s left)
2019-02-22 13:55:30,018 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135530004441
2019-02-22 13:55:30,038 [salt.minion      :1432][INFO    ][24562] Starting a new job with PID 24562
2019-02-22 13:55:30,066 [salt.minion      :1711][INFO    ][24562] Returning information for job: 20190222135530004441
2019-02-22 13:55:53,288 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (834.435613871s left)
2019-02-22 13:56:00,049 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135600037526
2019-02-22 13:56:00,069 [salt.minion      :1432][INFO    ][24608] Starting a new job with PID 24608
2019-02-22 13:56:00,097 [salt.minion      :1711][INFO    ][24608] Returning information for job: 20190222135600037526
2019-02-22 13:56:25,936 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (801.786973s left)
2019-02-22 13:56:30,115 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135630107741
2019-02-22 13:56:30,138 [salt.minion      :1432][INFO    ][24665] Starting a new job with PID 24665
2019-02-22 13:56:30,165 [salt.minion      :1711][INFO    ][24665] Returning information for job: 20190222135630107741
2019-02-22 13:56:58,765 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (768.958194971s left)
2019-02-22 13:57:00,299 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135700284030
2019-02-22 13:57:00,321 [salt.minion      :1432][INFO    ][24710] Starting a new job with PID 24710
2019-02-22 13:57:00,348 [salt.minion      :1711][INFO    ][24710] Returning information for job: 20190222135700284030
2019-02-22 13:57:30,415 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135730405611
2019-02-22 13:57:30,433 [salt.minion      :1432][INFO    ][24770] Starting a new job with PID 24770
2019-02-22 13:57:30,464 [salt.minion      :1711][INFO    ][24770] Returning information for job: 20190222135730405611
2019-02-22 13:57:31,404 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (736.319140911s left)
2019-02-22 13:58:00,480 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135800469880
2019-02-22 13:58:00,500 [salt.minion      :1432][INFO    ][24810] Starting a new job with PID 24810
2019-02-22 13:58:00,526 [salt.minion      :1711][INFO    ][24810] Returning information for job: 20190222135800469880
2019-02-22 13:58:04,325 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (703.39834094s left)
2019-02-22 13:58:30,590 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135830576437
2019-02-22 13:58:30,611 [salt.minion      :1432][INFO    ][24867] Starting a new job with PID 24867
2019-02-22 13:58:30,643 [salt.minion      :1711][INFO    ][24867] Returning information for job: 20190222135830576437
2019-02-22 13:58:37,097 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (670.626394033s left)
2019-02-22 13:59:00,677 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135900665782
2019-02-22 13:59:00,701 [salt.minion      :1432][INFO    ][24917] Starting a new job with PID 24917
2019-02-22 13:59:00,729 [salt.minion      :1711][INFO    ][24917] Returning information for job: 20190222135900665782
2019-02-22 13:59:09,732 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (637.990782976s left)
2019-02-22 13:59:30,885 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222135930873171
2019-02-22 13:59:30,906 [salt.minion      :1432][INFO    ][24974] Starting a new job with PID 24974
2019-02-22 13:59:30,936 [salt.minion      :1711][INFO    ][24974] Returning information for job: 20190222135930873171
2019-02-22 13:59:42,774 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (604.949751854s left)
2019-02-22 14:00:01,086 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140001079266
2019-02-22 14:00:01,101 [salt.minion      :1432][INFO    ][25144] Starting a new job with PID 25144
2019-02-22 14:00:01,127 [salt.minion      :1711][INFO    ][25144] Returning information for job: 20190222140001079266
2019-02-22 14:00:15,509 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (572.214017868s left)
2019-02-22 14:00:31,292 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140031277952
2019-02-22 14:00:31,314 [salt.minion      :1432][INFO    ][25201] Starting a new job with PID 25201
2019-02-22 14:00:31,342 [salt.minion      :1711][INFO    ][25201] Returning information for job: 20190222140031277952
2019-02-22 14:00:48,173 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (539.55054307s left)
2019-02-22 14:01:01,341 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140101332715
2019-02-22 14:01:01,358 [salt.minion      :1432][INFO    ][25245] Starting a new job with PID 25245
2019-02-22 14:01:01,389 [salt.minion      :1711][INFO    ][25245] Returning information for job: 20190222140101332715
2019-02-22 14:01:20,851 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (506.871782064s left)
2019-02-22 14:01:31,549 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140131535039
2019-02-22 14:01:31,569 [salt.minion      :1432][INFO    ][25304] Starting a new job with PID 25304
2019-02-22 14:01:31,601 [salt.minion      :1711][INFO    ][25304] Returning information for job: 20190222140131535039
2019-02-22 14:01:53,834 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (473.889241934s left)
2019-02-22 14:02:01,724 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140201709665
2019-02-22 14:02:01,742 [salt.minion      :1432][INFO    ][25351] Starting a new job with PID 25351
2019-02-22 14:02:01,774 [salt.minion      :1711][INFO    ][25351] Returning information for job: 20190222140201709665
2019-02-22 14:02:26,519 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (441.204047918s left)
2019-02-22 14:02:31,782 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140231768821
2019-02-22 14:02:31,800 [salt.minion      :1432][INFO    ][25410] Starting a new job with PID 25410
2019-02-22 14:02:31,832 [salt.minion      :1711][INFO    ][25410] Returning information for job: 20190222140231768821
2019-02-22 14:02:59,239 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (408.483763933s left)
2019-02-22 14:03:01,945 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140301929003
2019-02-22 14:03:01,964 [salt.minion      :1432][INFO    ][25458] Starting a new job with PID 25458
2019-02-22 14:03:01,996 [salt.minion      :1711][INFO    ][25458] Returning information for job: 20190222140301929003
2019-02-22 14:03:31,878 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (375.84524703s left)
2019-02-22 14:03:32,021 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140332007572
2019-02-22 14:03:32,037 [salt.minion      :1432][INFO    ][25515] Starting a new job with PID 25515
2019-02-22 14:03:32,069 [salt.minion      :1711][INFO    ][25515] Returning information for job: 20190222140332007572
2019-02-22 14:04:02,182 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140402174802
2019-02-22 14:04:02,197 [salt.minion      :1432][INFO    ][25557] Starting a new job with PID 25557
2019-02-22 14:04:02,228 [salt.minion      :1711][INFO    ][25557] Returning information for job: 20190222140402174802
2019-02-22 14:04:04,517 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (343.205857038s left)
2019-02-22 14:04:32,362 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140432350748
2019-02-22 14:04:32,382 [salt.minion      :1432][INFO    ][25613] Starting a new job with PID 25613
2019-02-22 14:04:32,412 [salt.minion      :1711][INFO    ][25613] Returning information for job: 20190222140432350748
2019-02-22 14:04:37,271 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (310.452113867s left)
2019-02-22 14:05:02,509 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140502497972
2019-02-22 14:05:02,528 [salt.minion      :1432][INFO    ][25666] Starting a new job with PID 25666
2019-02-22 14:05:02,558 [salt.minion      :1711][INFO    ][25666] Returning information for job: 20190222140502497972
2019-02-22 14:05:10,069 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (277.654739857s left)
2019-02-22 14:05:32,541 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140532532493
2019-02-22 14:05:32,554 [salt.minion      :1432][INFO    ][25737] Starting a new job with PID 25737
2019-02-22 14:05:32,584 [salt.minion      :1711][INFO    ][25737] Returning information for job: 20190222140532532493
2019-02-22 14:05:42,558 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (245.164910078s left)
2019-02-22 14:06:02,724 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140602717415
2019-02-22 14:06:02,737 [salt.minion      :1432][INFO    ][25783] Starting a new job with PID 25783
2019-02-22 14:06:02,768 [salt.minion      :1711][INFO    ][25783] Returning information for job: 20190222140602717415
2019-02-22 14:06:15,026 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (212.697376966s left)
2019-02-22 14:06:32,788 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140632780992
2019-02-22 14:06:32,804 [salt.minion      :1432][INFO    ][25842] Starting a new job with PID 25842
2019-02-22 14:06:32,844 [salt.minion      :1711][INFO    ][25842] Returning information for job: 20190222140632780992
2019-02-22 14:06:47,476 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (180.247003078s left)
2019-02-22 14:07:02,886 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140702874940
2019-02-22 14:07:02,906 [salt.minion      :1432][INFO    ][25887] Starting a new job with PID 25887
2019-02-22 14:07:02,938 [salt.minion      :1711][INFO    ][25887] Returning information for job: 20190222140702874940
2019-02-22 14:07:20,284 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (147.439008951s left)
2019-02-22 14:07:33,077 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140733070019
2019-02-22 14:07:33,092 [salt.minion      :1432][INFO    ][25946] Starting a new job with PID 25946
2019-02-22 14:07:33,120 [salt.minion      :1711][INFO    ][25946] Returning information for job: 20190222140733070019
2019-02-22 14:07:52,538 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (115.185544014s left)
2019-02-22 14:08:03,211 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140803202412
2019-02-22 14:08:03,226 [salt.minion      :1432][INFO    ][25992] Starting a new job with PID 25992
2019-02-22 14:08:03,257 [salt.minion      :1711][INFO    ][25992] Returning information for job: 20190222140803202412
2019-02-22 14:08:24,858 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (82.8652160168s left)
2019-02-22 14:08:33,360 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140833349950
2019-02-22 14:08:33,378 [salt.minion      :1432][INFO    ][26049] Starting a new job with PID 26049
2019-02-22 14:08:33,406 [salt.minion      :1711][INFO    ][26049] Returning information for job: 20190222140833349950
2019-02-22 14:08:57,198 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (50.5250229836s left)
2019-02-22 14:09:03,509 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140903501056
2019-02-22 14:09:03,522 [salt.minion      :1432][INFO    ][26095] Starting a new job with PID 26095
2019-02-22 14:09:03,551 [salt.minion      :1711][INFO    ][26095] Returning information for job: 20190222140903501056
2019-02-22 14:09:29,764 [salt.loaded.ext.module.maas:1023][INFO    ][19864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (17.9592418671s left)
2019-02-22 14:09:33,681 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222140933669027
2019-02-22 14:09:33,701 [salt.minion      :1432][INFO    ][26152] Starting a new job with PID 26152
2019-02-22 14:09:33,730 [salt.minion      :1711][INFO    ][26152] Returning information for job: 20190222140933669027
2019-02-22 14:10:02,172 [salt.state       :302 ][ERROR   ][19864] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-02-22 14:10:02,172 [salt.state       :1951][INFO    ][19864] Completed state [maas.wait_for_machine_status] at time 14:10:02.172411 duration_in_ms=2264458.955
2019-02-22 14:10:02,177 [salt.minion      :1711][INFO    ][19864] Returning information for job: 20190222133210053560
2019-02-22 14:10:13,177 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command pillar.get with jid 20190222141013170335
2019-02-22 14:10:13,190 [salt.minion      :1432][INFO    ][26228] Starting a new job with PID 26228
2019-02-22 14:10:13,199 [salt.minion      :1711][INFO    ][26228] Returning information for job: 20190222141013170335
2019-02-22 14:10:13,898 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command service.status with jid 20190222141013891710
2019-02-22 14:10:13,914 [salt.minion      :1432][INFO    ][26234] Starting a new job with PID 26234
2019-02-22 14:10:14,673 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][26234] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-02-22 14:10:14,708 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][26234] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-02-22 14:10:14,725 [salt.minion      :1711][INFO    ][26234] Returning information for job: 20190222141013891710
2019-02-22 14:10:15,428 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222141015418119
2019-02-22 14:10:15,444 [salt.minion      :1432][INFO    ][26247] Starting a new job with PID 26247
2019-02-22 14:10:21,286 [salt.state       :915 ][INFO    ][26247] Loading fresh modules for state activity
2019-02-22 14:10:21,848 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command 'salt-minion --version' in directory '/root'
2019-02-22 14:10:22,192 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command 'salt-minion --version' in directory '/root'
2019-02-22 14:10:23,145 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command 'salt-minion --version' in directory '/root'
2019-02-22 14:10:23,481 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command 'salt-minion --version' in directory '/root'
2019-02-22 14:10:25,534 [salt.state       :1780][INFO    ][26247] Running state [salt-minion] at time 14:10:25.534354
2019-02-22 14:10:25,534 [salt.state       :1813][INFO    ][26247] Executing state pkg.installed for [salt-minion]
2019-02-22 14:10:25,535 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-22 14:10:25,631 [salt.state       :300 ][INFO    ][26247] All specified packages are already installed
2019-02-22 14:10:25,632 [salt.state       :1951][INFO    ][26247] Completed state [salt-minion] at time 14:10:25.632051 duration_in_ms=97.697
2019-02-22 14:10:25,632 [salt.state       :1780][INFO    ][26247] Running state [salt_minion_dependency_packages] at time 14:10:25.632413
2019-02-22 14:10:25,632 [salt.state       :1813][INFO    ][26247] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-02-22 14:10:25,642 [salt.state       :300 ][INFO    ][26247] All specified packages are already installed
2019-02-22 14:10:25,643 [salt.state       :1951][INFO    ][26247] Completed state [salt_minion_dependency_packages] at time 14:10:25.643142 duration_in_ms=10.729
2019-02-22 14:10:25,646 [salt.state       :1780][INFO    ][26247] Running state [/etc/salt/minion.d/minion.conf] at time 14:10:25.646236
2019-02-22 14:10:25,646 [salt.state       :1813][INFO    ][26247] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-02-22 14:10:25,911 [salt.state       :300 ][INFO    ][26247] File /etc/salt/minion.d/minion.conf is in the correct state
2019-02-22 14:10:25,912 [salt.state       :1951][INFO    ][26247] Completed state [/etc/salt/minion.d/minion.conf] at time 14:10:25.912111 duration_in_ms=265.875
2019-02-22 14:10:25,912 [salt.state       :1780][INFO    ][26247] Running state [python-netaddr] at time 14:10:25.912412
2019-02-22 14:10:25,912 [salt.state       :1813][INFO    ][26247] Executing state pkg.installed for [python-netaddr]
2019-02-22 14:10:25,922 [salt.state       :300 ][INFO    ][26247] All specified packages are already installed
2019-02-22 14:10:25,922 [salt.state       :1951][INFO    ][26247] Completed state [python-netaddr] at time 14:10:25.922660 duration_in_ms=10.248
2019-02-22 14:10:25,926 [salt.state       :1780][INFO    ][26247] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 14:10:25.926415
2019-02-22 14:10:25,926 [salt.state       :1813][INFO    ][26247] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-02-22 14:10:25,940 [salt.state       :300 ][INFO    ][26247] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-02-22 14:10:25,940 [salt.state       :1951][INFO    ][26247] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 14:10:25.940763 duration_in_ms=14.347
2019-02-22 14:10:25,942 [salt.state       :1780][INFO    ][26247] Running state [salt-minion] at time 14:10:25.941947
2019-02-22 14:10:25,942 [salt.state       :1813][INFO    ][26247] Executing state service.running for [salt-minion]
2019-02-22 14:10:25,942 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-22 14:10:25,970 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-02-22 14:10:25,984 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-02-22 14:10:25,994 [salt.state       :300 ][INFO    ][26247] The service salt-minion is already running
2019-02-22 14:10:25,996 [salt.state       :1951][INFO    ][26247] Completed state [salt-minion] at time 14:10:25.994819 duration_in_ms=52.872
2019-02-22 14:10:25,998 [salt.state       :1780][INFO    ][26247] Running state [/etc/salt/grains.d] at time 14:10:25.998403
2019-02-22 14:10:25,998 [salt.state       :1813][INFO    ][26247] Executing state file.directory for [/etc/salt/grains.d]
2019-02-22 14:10:25,999 [salt.state       :300 ][INFO    ][26247] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-02-22 14:10:26,000 [salt.state       :1951][INFO    ][26247] Completed state [/etc/salt/grains.d] at time 14:10:26.000073 duration_in_ms=1.67
2019-02-22 14:10:26,000 [salt.state       :1780][INFO    ][26247] Running state [/etc/salt/grains] at time 14:10:26.000771
2019-02-22 14:10:26,001 [salt.state       :1813][INFO    ][26247] Executing state file.managed for [/etc/salt/grains]
2019-02-22 14:10:26,001 [salt.state       :300 ][INFO    ][26247] File /etc/salt/grains exists with proper permissions. No changes made.
2019-02-22 14:10:26,001 [salt.state       :1951][INFO    ][26247] Completed state [/etc/salt/grains] at time 14:10:26.001877 duration_in_ms=1.106
2019-02-22 14:10:26,002 [salt.state       :1780][INFO    ][26247] Running state [/etc/salt/grains.d/placeholder] at time 14:10:26.002385
2019-02-22 14:10:26,002 [salt.state       :1813][INFO    ][26247] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-02-22 14:10:26,003 [salt.state       :300 ][INFO    ][26247] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-02-22 14:10:26,003 [salt.state       :1951][INFO    ][26247] Completed state [/etc/salt/grains.d/placeholder] at time 14:10:26.003526 duration_in_ms=1.141
2019-02-22 14:10:26,004 [salt.state       :1780][INFO    ][26247] Running state [/etc/salt/grains.d/sphinx] at time 14:10:26.004042
2019-02-22 14:10:26,004 [salt.state       :1813][INFO    ][26247] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-02-22 14:10:26,006 [salt.state       :300 ][INFO    ][26247] File /etc/salt/grains.d/sphinx is in the correct state
2019-02-22 14:10:26,006 [salt.state       :1951][INFO    ][26247] Completed state [/etc/salt/grains.d/sphinx] at time 14:10:26.006348 duration_in_ms=2.304
2019-02-22 14:10:26,011 [salt.state       :1780][INFO    ][26247] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 14:10:26.011357
2019-02-22 14:10:26,011 [salt.state       :1813][INFO    ][26247] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-02-22 14:10:26,012 [salt.state       :300 ][INFO    ][26247] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-02-22 14:10:26,012 [salt.state       :1951][INFO    ][26247] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 14:10:26.012670 duration_in_ms=1.312
2019-02-22 14:10:26,013 [salt.state       :1780][INFO    ][26247] Running state [/etc/salt/grains.d/dns_records] at time 14:10:26.013523
2019-02-22 14:10:26,014 [salt.state       :1813][INFO    ][26247] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-02-22 14:10:26,015 [salt.state       :300 ][INFO    ][26247] File /etc/salt/grains.d/dns_records is in the correct state
2019-02-22 14:10:26,016 [salt.state       :1951][INFO    ][26247] Completed state [/etc/salt/grains.d/dns_records] at time 14:10:26.016171 duration_in_ms=2.649
2019-02-22 14:10:26,017 [salt.state       :1780][INFO    ][26247] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 14:10:26.017157
2019-02-22 14:10:26,017 [salt.state       :1813][INFO    ][26247] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-02-22 14:10:26,017 [salt.state       :300 ][INFO    ][26247] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-02-22 14:10:26,018 [salt.state       :1951][INFO    ][26247] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 14:10:26.018010 duration_in_ms=0.853
2019-02-22 14:10:26,018 [salt.state       :1780][INFO    ][26247] Running state [/etc/salt/grains.d/salt] at time 14:10:26.018523
2019-02-22 14:10:26,018 [salt.state       :1813][INFO    ][26247] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-02-22 14:10:26,022 [salt.state       :300 ][INFO    ][26247] File /etc/salt/grains.d/salt is in the correct state
2019-02-22 14:10:26,022 [salt.state       :1951][INFO    ][26247] Completed state [/etc/salt/grains.d/salt] at time 14:10:26.022786 duration_in_ms=4.263
2019-02-22 14:10:26,023 [salt.state       :1780][INFO    ][26247] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 14:10:26.023741
2019-02-22 14:10:26,024 [salt.state       :1813][INFO    ][26247] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-02-22 14:10:26,024 [salt.state       :300 ][INFO    ][26247] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-02-22 14:10:26,024 [salt.state       :1951][INFO    ][26247] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 14:10:26.024593 duration_in_ms=0.852
2019-02-22 14:10:26,026 [salt.state       :1780][INFO    ][26247] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 14:10:26.026544
2019-02-22 14:10:26,026 [salt.state       :1813][INFO    ][26247] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-02-22 14:10:26,027 [salt.state       :300 ][INFO    ][26247] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-02-22 14:10:26,027 [salt.state       :1951][INFO    ][26247] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 14:10:26.027400 duration_in_ms=0.856
2019-02-22 14:10:26,028 [salt.state       :1780][INFO    ][26247] Running state [mine.update] at time 14:10:26.028085
2019-02-22 14:10:26,028 [salt.state       :1813][INFO    ][26247] Executing state module.wait for [mine.update]
2019-02-22 14:10:26,028 [salt.state       :300 ][INFO    ][26247] No changes made for mine.update
2019-02-22 14:10:26,029 [salt.state       :1951][INFO    ][26247] Completed state [mine.update] at time 14:10:26.029160 duration_in_ms=1.075
2019-02-22 14:10:26,029 [salt.state       :1780][INFO    ][26247] Running state [ca-certificates] at time 14:10:26.029432
2019-02-22 14:10:26,029 [salt.state       :1813][INFO    ][26247] Executing state pkg.installed for [ca-certificates]
2019-02-22 14:10:26,041 [salt.state       :300 ][INFO    ][26247] All specified packages are already installed
2019-02-22 14:10:26,041 [salt.state       :1951][INFO    ][26247] Completed state [ca-certificates] at time 14:10:26.041199 duration_in_ms=11.767
2019-02-22 14:10:26,041 [salt.state       :1780][INFO    ][26247] Running state [update-ca-certificates] at time 14:10:26.041920
2019-02-22 14:10:26,042 [salt.state       :1813][INFO    ][26247] Executing state cmd.wait for [update-ca-certificates]
2019-02-22 14:10:26,042 [salt.state       :300 ][INFO    ][26247] No changes made for update-ca-certificates
2019-02-22 14:10:26,042 [salt.state       :1951][INFO    ][26247] Completed state [update-ca-certificates] at time 14:10:26.042718 duration_in_ms=0.798
2019-02-22 14:10:26,044 [salt.state       :1780][INFO    ][26247] Running state [iptables] at time 14:10:26.044098
2019-02-22 14:10:26,044 [salt.state       :1813][INFO    ][26247] Executing state pkg.installed for [iptables]
2019-02-22 14:10:26,053 [salt.state       :300 ][INFO    ][26247] All specified packages are already installed
2019-02-22 14:10:26,053 [salt.state       :1951][INFO    ][26247] Completed state [iptables] at time 14:10:26.053630 duration_in_ms=9.531
2019-02-22 14:10:26,053 [salt.state       :1780][INFO    ][26247] Running state [iptables-persistent] at time 14:10:26.053907
2019-02-22 14:10:26,054 [salt.state       :1813][INFO    ][26247] Executing state pkg.installed for [iptables-persistent]
2019-02-22 14:10:26,063 [salt.state       :300 ][INFO    ][26247] All specified packages are already installed
2019-02-22 14:10:26,063 [salt.state       :1951][INFO    ][26247] Completed state [iptables-persistent] at time 14:10:26.063671 duration_in_ms=9.763
2019-02-22 14:10:26,064 [salt.state       :1780][INFO    ][26247] Running state [iptables_modules_v4_load] at time 14:10:26.064730
2019-02-22 14:10:26,065 [salt.state       :1813][INFO    ][26247] Executing state kmod.present for [iptables_modules_v4_load]
2019-02-22 14:10:26,065 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command 'lsmod' in directory '/root'
2019-02-22 14:10:26,084 [salt.state       :300 ][INFO    ][26247] Kernel modules iptable_filter, ip_tables are already present
2019-02-22 14:10:26,084 [salt.state       :1951][INFO    ][26247] Completed state [iptables_modules_v4_load] at time 14:10:26.084550 duration_in_ms=19.818
2019-02-22 14:10:26,085 [salt.state       :1780][INFO    ][26247] Running state [/etc/iptables/rules.v4] at time 14:10:26.085715
2019-02-22 14:10:26,086 [salt.state       :1813][INFO    ][26247] Executing state file.managed for [/etc/iptables/rules.v4]
2019-02-22 14:10:26,182 [salt.state       :300 ][INFO    ][26247] File /etc/iptables/rules.v4 is in the correct state
2019-02-22 14:10:26,182 [salt.state       :1951][INFO    ][26247] Completed state [/etc/iptables/rules.v4] at time 14:10:26.182629 duration_in_ms=96.914
2019-02-22 14:10:26,184 [salt.state       :1780][INFO    ][26247] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 14:10:26.184209
2019-02-22 14:10:26,184 [salt.state       :1813][INFO    ][26247] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-02-22 14:10:26,185 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-02-22 14:10:26,202 [salt.state       :300 ][INFO    ][26247] onlyif execution failed
2019-02-22 14:10:26,202 [salt.state       :1951][INFO    ][26247] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 14:10:26.202564 duration_in_ms=18.355
2019-02-22 14:10:26,204 [salt.state       :1780][INFO    ][26247] Running state [netfilter-persistent] at time 14:10:26.204021
2019-02-22 14:10:26,204 [salt.state       :1813][INFO    ][26247] Executing state service.running for [netfilter-persistent]
2019-02-22 14:10:26,205 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-02-22 14:10:26,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-02-22 14:10:26,238 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-02-22 14:10:26,250 [salt.state       :300 ][INFO    ][26247] The service netfilter-persistent is already running
2019-02-22 14:10:26,250 [salt.state       :1951][INFO    ][26247] Completed state [netfilter-persistent] at time 14:10:26.250780 duration_in_ms=46.758
2019-02-22 14:10:26,251 [salt.state       :1780][INFO    ][26247] Running state [iptables_extra.remove_stale_tables] at time 14:10:26.251719
2019-02-22 14:10:26,252 [salt.state       :1813][INFO    ][26247] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-02-22 14:10:26,252 [salt.state       :300 ][INFO    ][26247] No changes made for iptables_extra.remove_stale_tables
2019-02-22 14:10:26,252 [salt.state       :1951][INFO    ][26247] Completed state [iptables_extra.remove_stale_tables] at time 14:10:26.252618 duration_in_ms=0.899
2019-02-22 14:10:26,252 [salt.state       :1780][INFO    ][26247] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 14:10:26.252896
2019-02-22 14:10:26,253 [salt.state       :1813][INFO    ][26247] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-02-22 14:10:26,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26247] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-02-22 14:10:26,264 [salt.state       :300 ][INFO    ][26247] onlyif execution failed
2019-02-22 14:10:26,264 [salt.state       :1951][INFO    ][26247] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 14:10:26.264392 duration_in_ms=11.496
2019-02-22 14:10:26,265 [salt.state       :1780][INFO    ][26247] Running state [/etc/iptables/rules.v6] at time 14:10:26.265353
2019-02-22 14:10:26,265 [salt.state       :1813][INFO    ][26247] Executing state file.absent for [/etc/iptables/rules.v6]
2019-02-22 14:10:26,266 [salt.state       :300 ][INFO    ][26247] File /etc/iptables/rules.v6 is not present
2019-02-22 14:10:26,266 [salt.state       :1951][INFO    ][26247] Completed state [/etc/iptables/rules.v6] at time 14:10:26.266325 duration_in_ms=0.972
2019-02-22 14:10:26,267 [salt.state       :1780][INFO    ][26247] Running state [iptables_extra.flush_all] at time 14:10:26.267110
2019-02-22 14:10:26,267 [salt.state       :1813][INFO    ][26247] Executing state module.wait for [iptables_extra.flush_all]
2019-02-22 14:10:26,267 [salt.state       :300 ][INFO    ][26247] No changes made for iptables_extra.flush_all
2019-02-22 14:10:26,267 [salt.state       :1951][INFO    ][26247] Completed state [iptables_extra.flush_all] at time 14:10:26.267912 duration_in_ms=0.801
2019-02-22 14:10:26,273 [salt.minion      :1711][INFO    ][26247] Returning information for job: 20190222141015418119
2019-02-22 14:10:26,939 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222141026932415
2019-02-22 14:10:26,954 [salt.minion      :1432][INFO    ][26334] Starting a new job with PID 26334
2019-02-22 14:10:27,685 [salt.state       :915 ][INFO    ][26334] Loading fresh modules for state activity
2019-02-22 14:10:28,853 [salt.state       :1780][INFO    ][26334] Running state [maas-rack-controller] at time 14:10:28.853886
2019-02-22 14:10:28,854 [salt.state       :1813][INFO    ][26334] Executing state pkg.installed for [maas-rack-controller]
2019-02-22 14:10:28,855 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26334] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-22 14:10:28,953 [salt.state       :300 ][INFO    ][26334] All specified packages are already installed
2019-02-22 14:10:28,954 [salt.state       :1951][INFO    ][26334] Completed state [maas-rack-controller] at time 14:10:28.954128 duration_in_ms=100.242
2019-02-22 14:10:28,954 [salt.state       :1780][INFO    ][26334] Running state [ipmitool] at time 14:10:28.954514
2019-02-22 14:10:28,954 [salt.state       :1813][INFO    ][26334] Executing state pkg.installed for [ipmitool]
2019-02-22 14:10:29,012 [salt.state       :300 ][INFO    ][26334] All specified packages are already installed
2019-02-22 14:10:29,012 [salt.state       :1951][INFO    ][26334] Completed state [ipmitool] at time 14:10:29.012422 duration_in_ms=57.908
2019-02-22 14:10:29,015 [salt.state       :1780][INFO    ][26334] Running state [/etc/maas/rackd.conf] at time 14:10:29.015332
2019-02-22 14:10:29,015 [salt.state       :1813][INFO    ][26334] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-22 14:10:29,016 [salt.state       :300 ][INFO    ][26334] No changes needed to be made
2019-02-22 14:10:29,016 [salt.state       :1951][INFO    ][26334] Completed state [/etc/maas/rackd.conf] at time 14:10:29.016820 duration_in_ms=1.488
2019-02-22 14:10:29,017 [salt.state       :1780][INFO    ][26334] Running state [/etc/maas/rackd.conf] at time 14:10:29.017100
2019-02-22 14:10:29,017 [salt.state       :1813][INFO    ][26334] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-22 14:10:29,017 [salt.loaded.int.states.file:2298][WARNING ][26334] 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-02-22 14:10:29,018 [salt.state       :300 ][INFO    ][26334] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-22 14:10:29,018 [salt.state       :1951][INFO    ][26334] Completed state [/etc/maas/rackd.conf] at time 14:10:29.018547 duration_in_ms=1.447
2019-02-22 14:10:29,020 [salt.state       :1780][INFO    ][26334] Running state [maas-rackd] at time 14:10:29.020403
2019-02-22 14:10:29,020 [salt.state       :1813][INFO    ][26334] Executing state service.running for [maas-rackd]
2019-02-22 14:10:29,021 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26334] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-22 14:10:29,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26334] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-22 14:10:29,058 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26334] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-22 14:10:29,070 [salt.state       :300 ][INFO    ][26334] The service maas-rackd is already running
2019-02-22 14:10:29,070 [salt.state       :1951][INFO    ][26334] Completed state [maas-rackd] at time 14:10:29.070821 duration_in_ms=50.418
2019-02-22 14:10:29,072 [salt.minion      :1711][INFO    ][26334] Returning information for job: 20190222141026932415
2019-02-22 14:10:29,737 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222141029730723
2019-02-22 14:10:29,750 [salt.minion      :1432][INFO    ][26362] Starting a new job with PID 26362
2019-02-22 14:10:30,484 [salt.state       :915 ][INFO    ][26362] Loading fresh modules for state activity
2019-02-22 14:10:31,800 [salt.state       :1780][INFO    ][26362] Running state [maas-region-controller] at time 14:10:31.800851
2019-02-22 14:10:31,801 [salt.state       :1813][INFO    ][26362] Executing state pkg.installed for [maas-region-controller]
2019-02-22 14:10:31,802 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-22 14:10:31,893 [salt.state       :300 ][INFO    ][26362] All specified packages are already installed
2019-02-22 14:10:31,893 [salt.state       :1951][INFO    ][26362] Completed state [maas-region-controller] at time 14:10:31.893866 duration_in_ms=93.016
2019-02-22 14:10:31,894 [salt.state       :1780][INFO    ][26362] Running state [python-oauth] at time 14:10:31.894225
2019-02-22 14:10:31,894 [salt.state       :1813][INFO    ][26362] Executing state pkg.installed for [python-oauth]
2019-02-22 14:10:31,905 [salt.state       :300 ][INFO    ][26362] All specified packages are already installed
2019-02-22 14:10:31,905 [salt.state       :1951][INFO    ][26362] Completed state [python-oauth] at time 14:10:31.905437 duration_in_ms=11.212
2019-02-22 14:10:31,908 [salt.state       :1780][INFO    ][26362] Running state [/etc/maas/regiond.conf] at time 14:10:31.908824
2019-02-22 14:10:31,909 [salt.state       :1813][INFO    ][26362] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-22 14:10:31,916 [salt.state       :300 ][INFO    ][26362] No changes needed to be made
2019-02-22 14:10:31,916 [salt.state       :1951][INFO    ][26362] Completed state [/etc/maas/regiond.conf] at time 14:10:31.916306 duration_in_ms=7.482
2019-02-22 14:10:31,916 [salt.state       :1780][INFO    ][26362] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 14:10:31.916817
2019-02-22 14:10:31,917 [salt.state       :1813][INFO    ][26362] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-22 14:10:31,968 [salt.state       :300 ][INFO    ][26362] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-02-22 14:10:31,969 [salt.state       :1951][INFO    ][26362] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 14:10:31.969062 duration_in_ms=52.244
2019-02-22 14:10:31,969 [salt.state       :1780][INFO    ][26362] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:10:31.969607
2019-02-22 14:10:31,969 [salt.state       :1813][INFO    ][26362] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-22 14:10:31,975 [salt.state       :300 ][INFO    ][26362] No changes needed to be made
2019-02-22 14:10:31,975 [salt.state       :1951][INFO    ][26362] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:10:31.975900 duration_in_ms=6.293
2019-02-22 14:10:31,976 [salt.state       :1780][INFO    ][26362] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:10:31.976427
2019-02-22 14:10:31,976 [salt.state       :1813][INFO    ][26362] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-22 14:10:31,980 [salt.state       :300 ][INFO    ][26362] No changes needed to be made
2019-02-22 14:10:31,981 [salt.state       :1951][INFO    ][26362] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 14:10:31.981102 duration_in_ms=4.675
2019-02-22 14:10:31,981 [salt.state       :1780][INFO    ][26362] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 14:10:31.981624
2019-02-22 14:10:31,981 [salt.state       :1813][INFO    ][26362] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-22 14:10:32,006 [salt.state       :300 ][INFO    ][26362] No changes needed to be made
2019-02-22 14:10:32,006 [salt.state       :1951][INFO    ][26362] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 14:10:32.006370 duration_in_ms=24.746
2019-02-22 14:10:32,008 [salt.state       :1780][INFO    ][26362] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 14:10:32.008409
2019-02-22 14:10:32,008 [salt.state       :1813][INFO    ][26362] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-22 14:10:32,018 [salt.state       :300 ][INFO    ][26362] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-02-22 14:10:32,018 [salt.state       :1951][INFO    ][26362] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 14:10:32.018722 duration_in_ms=10.312
2019-02-22 14:10:32,022 [salt.state       :1780][INFO    ][26362] Running state [a2enmod headers] at time 14:10:32.022632
2019-02-22 14:10:32,022 [salt.state       :1813][INFO    ][26362] Executing state cmd.run for [a2enmod headers]
2019-02-22 14:10:32,023 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command 'a2enmod headers' in directory '/root'
2019-02-22 14:10:32,083 [salt.state       :300 ][INFO    ][26362] {'pid': 26381, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-02-22 14:10:32,084 [salt.state       :1951][INFO    ][26362] Completed state [a2enmod headers] at time 14:10:32.084158 duration_in_ms=61.526
2019-02-22 14:10:32,084 [salt.state       :1780][INFO    ][26362] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 14:10:32.084544
2019-02-22 14:10:32,084 [salt.state       :1813][INFO    ][26362] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-22 14:10:32,101 [salt.state       :300 ][INFO    ][26362] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-02-22 14:10:32,101 [salt.state       :1951][INFO    ][26362] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 14:10:32.101640 duration_in_ms=17.095
2019-02-22 14:10:32,102 [salt.state       :1780][INFO    ][26362] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 14:10:32.102275
2019-02-22 14:10:32,102 [salt.state       :1813][INFO    ][26362] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-22 14:10:32,156 [salt.state       :300 ][INFO    ][26362] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-02-22 14:10:32,156 [salt.state       :1951][INFO    ][26362] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 14:10:32.156453 duration_in_ms=54.177
2019-02-22 14:10:32,157 [salt.state       :1780][INFO    ][26362] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 14:10:32.156994
2019-02-22 14:10:32,157 [salt.state       :1813][INFO    ][26362] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-22 14:10:32,207 [salt.state       :300 ][INFO    ][26362] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-02-22 14:10:32,207 [salt.state       :1951][INFO    ][26362] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 14:10:32.207827 duration_in_ms=50.833
2019-02-22 14:10:32,208 [salt.state       :1780][INFO    ][26362] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 14:10:32.208375
2019-02-22 14:10:32,208 [salt.state       :1813][INFO    ][26362] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-22 14:10:32,279 [salt.state       :300 ][INFO    ][26362] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-02-22 14:10:32,279 [salt.state       :1951][INFO    ][26362] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 14:10:32.279254 duration_in_ms=70.878
2019-02-22 14:10:32,279 [salt.state       :1780][INFO    ][26362] Running state [/root/.pgpass] at time 14:10:32.279564
2019-02-22 14:10:32,279 [salt.state       :1813][INFO    ][26362] Executing state file.managed for [/root/.pgpass]
2019-02-22 14:10:32,322 [salt.state       :300 ][INFO    ][26362] File /root/.pgpass is in the correct state
2019-02-22 14:10:32,322 [salt.state       :1951][INFO    ][26362] Completed state [/root/.pgpass] at time 14:10:32.322830 duration_in_ms=43.266
2019-02-22 14:10:32,327 [salt.state       :1780][INFO    ][26362] Running state [maas-region syncdb --noinput] at time 14:10:32.327855
2019-02-22 14:10:32,328 [salt.state       :1813][INFO    ][26362] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-22 14:10:32,328 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-22 14:10:35,014 [salt.state       :300 ][INFO    ][26362] {'pid': 26394, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, sessions, sites, auth, piston3, metadataserver, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-22 14:10:35,015 [salt.state       :1951][INFO    ][26362] Completed state [maas-region syncdb --noinput] at time 14:10:35.015548 duration_in_ms=2687.692
2019-02-22 14:10:35,015 [salt.state       :2022][WARNING ][26362] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-22 14:10:35,017 [salt.state       :1780][INFO    ][26362] Running state [maas-regiond] at time 14:10:35.017662
2019-02-22 14:10:35,018 [salt.state       :1813][INFO    ][26362] Executing state service.running for [maas-regiond]
2019-02-22 14:10:35,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-22 14:10:35,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-22 14:10:35,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-22 14:10:35,073 [salt.state       :300 ][INFO    ][26362] The service maas-regiond is already running
2019-02-22 14:10:35,073 [salt.state       :1951][INFO    ][26362] Completed state [maas-regiond] at time 14:10:35.073904 duration_in_ms=56.241
2019-02-22 14:10:35,076 [salt.state       :1780][INFO    ][26362] Running state [bind9] at time 14:10:35.076002
2019-02-22 14:10:35,076 [salt.state       :1813][INFO    ][26362] Executing state service.running for [bind9]
2019-02-22 14:10:35,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-22 14:10:35,094 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-22 14:10:35,108 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-22 14:10:35,121 [salt.state       :300 ][INFO    ][26362] The service bind9 is already running
2019-02-22 14:10:35,121 [salt.state       :1951][INFO    ][26362] Completed state [bind9] at time 14:10:35.121400 duration_in_ms=45.398
2019-02-22 14:10:35,123 [salt.state       :1780][INFO    ][26362] Running state [apache2] at time 14:10:35.123257
2019-02-22 14:10:35,123 [salt.state       :1813][INFO    ][26362] Executing state service.running for [apache2]
2019-02-22 14:10:35,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-22 14:10:35,137 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-22 14:10:35,150 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-22 14:10:35,168 [salt.state       :300 ][INFO    ][26362] The service apache2 is already running
2019-02-22 14:10:35,168 [salt.state       :1951][INFO    ][26362] Completed state [apache2] at time 14:10:35.168867 duration_in_ms=45.609
2019-02-22 14:10:35,170 [salt.state       :1780][INFO    ][26362] Running state [maasng.wait_for_http_code] at time 14:10:35.170012
2019-02-22 14:10:35,170 [salt.state       :1813][INFO    ][26362] Executing state module.run for [maasng.wait_for_http_code]
2019-02-22 14:10:35,170 [salt.utils.decorators:613 ][WARNING ][26362] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 14:10:35,280 [salt.state       :300 ][INFO    ][26362] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-22 14:10:35,280 [salt.state       :1951][INFO    ][26362] Completed state [maasng.wait_for_http_code] at time 14:10:35.280487 duration_in_ms=110.474
2019-02-22 14:10:35,281 [salt.state       :1780][INFO    ][26362] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 14:10:35.281355
2019-02-22 14:10:35,281 [salt.state       :1813][INFO    ][26362] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-22 14:10:35,282 [salt.state       :300 ][INFO    ][26362] /var/lib/maas/.setup_admin exists
2019-02-22 14:10:35,282 [salt.state       :1951][INFO    ][26362] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 14:10:35.282346 duration_in_ms=0.991
2019-02-22 14:10:35,284 [salt.state       :1780][INFO    ][26362] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:10:35.284180
2019-02-22 14:10:35,284 [salt.state       :1813][INFO    ][26362] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 14:10:35,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26362] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 14:10:37,164 [salt.state       :300 ][INFO    ][26362] {'pid': 26417, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 14:10:37,164 [salt.state       :1951][INFO    ][26362] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:10:37.164701 duration_in_ms=1880.521
2019-02-22 14:10:37,170 [salt.state       :1780][INFO    ][26362] Running state [maas_region_boot_source_resources_mirror] at time 14:10:37.170395
2019-02-22 14:10:37,170 [salt.state       :1813][INFO    ][26362] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-22 14:10:37,241 [salt.state       :300 ][INFO    ][26362] {'changes': {}}
2019-02-22 14:10:37,241 [salt.state       :1951][INFO    ][26362] Completed state [maas_region_boot_source_resources_mirror] at time 14:10:37.241508 duration_in_ms=71.113
2019-02-22 14:10:37,242 [salt.state       :1780][INFO    ][26362] Running state [maasng.boot_resources_import] at time 14:10:37.242256
2019-02-22 14:10:37,242 [salt.state       :1813][INFO    ][26362] Executing state module.run for [maasng.boot_resources_import]
2019-02-22 14:10:37,243 [salt.utils.decorators:613 ][WARNING ][26362] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 14:10:37,307 [salt.loaded.ext.module.maasng:1600][INFO    ][26362] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-22 14:10:42,340 [salt.loaded.ext.module.maasng:1600][INFO    ][26362] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-22 14:10:44,852 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141044844603
2019-02-22 14:10:44,868 [salt.minion      :1432][INFO    ][26466] Starting a new job with PID 26466
2019-02-22 14:10:44,900 [salt.minion      :1711][INFO    ][26466] Returning information for job: 20190222141044844603
2019-02-22 14:10:47,428 [salt.state       :300 ][INFO    ][26362] {'ret': True}
2019-02-22 14:10:47,429 [salt.state       :1951][INFO    ][26362] Completed state [maasng.boot_resources_import] at time 14:10:47.429177 duration_in_ms=10186.921
2019-02-22 14:10:47,430 [salt.state       :1780][INFO    ][26362] Running state [maas_region_boot_sources_selection_xenial] at time 14:10:47.429956
2019-02-22 14:10:47,430 [salt.state       :1813][INFO    ][26362] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-22 14:10:47,561 [salt.state       :300 ][INFO    ][26362] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-02-22 14:10:47,562 [salt.state       :1951][INFO    ][26362] Completed state [maas_region_boot_sources_selection_xenial] at time 14:10:47.562139 duration_in_ms=132.181
2019-02-22 14:10:47,563 [salt.state       :1780][INFO    ][26362] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 14:10:47.563352
2019-02-22 14:10:47,563 [salt.state       :1813][INFO    ][26362] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-22 14:10:47,564 [salt.utils.decorators:613 ][WARNING ][26362] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 14:10:47,564 [salt.loaded.ext.module.maasng:1771][INFO    ][26362] boot-sources sync initiated for ALL Rack's
2019-02-22 14:10:48,421 [salt.state       :300 ][INFO    ][26362] {'ret': True}
2019-02-22 14:10:48,422 [salt.state       :1951][INFO    ][26362] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 14:10:48.422267 duration_in_ms=858.915
2019-02-22 14:10:48,423 [salt.state       :1780][INFO    ][26362] Running state [maas.process_maas_config] at time 14:10:48.423906
2019-02-22 14:10:48,424 [salt.state       :1813][INFO    ][26362] Executing state module.run for [maas.process_maas_config]
2019-02-22 14:10:48,424 [salt.utils.decorators:613 ][WARNING ][26362] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 14:10:48,425 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=enable_http_proxy value=True
2019-02-22 14:10:48,464 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=upstream_dns value=8.8.8.8
2019-02-22 14:10:48,502 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=commissioning_distro_series value=xenial
2019-02-22 14:10:48,556 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=default_osystem value=ubuntu
2019-02-22 14:10:48,597 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=active_discovery_interval value=600
2019-02-22 14:10:48,636 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=dnssec_validation value=no
2019-02-22 14:10:51,214 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=maas_name value=mas01
2019-02-22 14:10:51,260 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=network_discovery value=enabled
2019-02-22 14:10:51,352 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=enable_third_party_drivers value=True
2019-02-22 14:10:51,386 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=default_storage_layout value=lvm
2019-02-22 14:10:51,422 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=ntp_external_only value=True
2019-02-22 14:10:51,475 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-22 14:10:51,517 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=default_distro_series value=xenial
2019-02-22 14:10:51,576 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-02-22 14:10:51,674 [salt.state       :300 ][INFO    ][26362] {'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-02-22 14:10:51,674 [salt.state       :1951][INFO    ][26362] Completed state [maas.process_maas_config] at time 14:10:51.674504 duration_in_ms=3250.597
2019-02-22 14:10:51,676 [salt.state       :1780][INFO    ][26362] Running state [pxe_admin] at time 14:10:51.676521
2019-02-22 14:10:51,676 [salt.state       :1813][INFO    ][26362] Executing state maasng.fabric_present for [pxe_admin]
2019-02-22 14:10:51,729 [salt.loaded.ext.module.maasng:945 ][INFO    ][26362] [{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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, 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'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-02-22 14:10:51,792 [salt.loaded.ext.module.maasng:1008][WARNING ][26362] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-02-22 14:10:51,792 [salt.loaded.ext.module.maasng:1011][WARNING ][26362] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-02-22 14:10:51,854 [salt.state       :300 ][INFO    ][26362] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-22 14:10:51,857 [salt.state       :1951][INFO    ][26362] Completed state [pxe_admin] at time 14:10:51.854828 duration_in_ms=178.306
2019-02-22 14:10:51,857 [salt.state       :1780][INFO    ][26362] Running state [vlan 0] at time 14:10:51.857352
2019-02-22 14:10:51,857 [salt.state       :1813][INFO    ][26362] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-22 14:10:51,899 [salt.loaded.ext.module.maasng:945 ][INFO    ][26362] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-22 14:10:51,982 [salt.loaded.ext.module.maasng:945 ][INFO    ][26362] [{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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-02-22 14:10:52,183 [salt.loaded.ext.module.maasng:945 ][INFO    ][26362] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-22 14:10:52,247 [salt.state       :300 ][INFO    ][26362] {'new': 'Vlan untagged was updated'}
2019-02-22 14:10:52,248 [salt.state       :1951][INFO    ][26362] Completed state [vlan 0] at time 14:10:52.248143 duration_in_ms=390.791
2019-02-22 14:10:52,249 [salt.state       :1780][INFO    ][26362] Running state [192.168.11.0/24] at time 14:10:52.249127
2019-02-22 14:10:52,249 [salt.state       :1813][INFO    ][26362] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-22 14:10:52,401 [salt.loaded.ext.module.maasng:945 ][INFO    ][26362] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-22 14:10:52,402 [salt.loaded.ext.module.maasng:1235][WARNING ][26362] Ignoring parameter vlan:0
2019-02-22 14:10:52,473 [salt.state       :300 ][INFO    ][26362] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-22 14:10:52,473 [salt.state       :1951][INFO    ][26362] Completed state [192.168.11.0/24] at time 14:10:52.473491 duration_in_ms=224.363
2019-02-22 14:10:52,474 [salt.state       :1780][INFO    ][26362] Running state [maas_create_iprange_1] at time 14:10:52.474310
2019-02-22 14:10:52,474 [salt.state       :1813][INFO    ][26362] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-22 14:10:52,522 [salt.state       :300 ][INFO    ][26362] Iprange maas_create_iprange_1 already exist.
2019-02-22 14:10:52,522 [salt.state       :1951][INFO    ][26362] Completed state [maas_create_iprange_1] at time 14:10:52.522245 duration_in_ms=47.935
2019-02-22 14:10:52,522 [salt.state       :1780][INFO    ][26362] Running state [vlan 0] at time 14:10:52.522536
2019-02-22 14:10:52,523 [salt.state       :1813][INFO    ][26362] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-22 14:10:52,588 [salt.loaded.ext.module.maasng:945 ][INFO    ][26362] [{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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, 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'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', 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'pxe_admin', u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-02-22 14:10:52,675 [salt.loaded.ext.module.maasng:945 ][INFO    ][26362] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fgpfwa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-22 14:10:52,866 [salt.loaded.ext.module.maasng:945 ][INFO    ][26362] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'fgpfwa', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-22 14:10:52,932 [salt.state       :300 ][INFO    ][26362] {'new': 'Vlan untagged was updated'}
2019-02-22 14:10:52,932 [salt.state       :1951][INFO    ][26362] Completed state [vlan 0] at time 14:10:52.932701 duration_in_ms=410.164
2019-02-22 14:10:52,933 [salt.state       :1780][INFO    ][26362] Running state [opnfv] at time 14:10:52.933336
2019-02-22 14:10:52,933 [salt.state       :1813][INFO    ][26362] Executing state maasng.sshkey_present for [opnfv]
2019-02-22 14:10:52,975 [salt.loaded.ext.module.maasng:1903][INFO    ][26362] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'keysource': u''}]
2019-02-22 14:10:52,976 [salt.state       :300 ][INFO    ][26362] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-02-22 14:10:52,976 [salt.state       :1951][INFO    ][26362] Completed state [opnfv] at time 14:10:52.976418 duration_in_ms=43.082
2019-02-22 14:10:52,977 [salt.state       :1780][INFO    ][26362] Running state [maas.process_tags] at time 14:10:52.977015
2019-02-22 14:10:52,977 [salt.state       :1813][INFO    ][26362] Executing state module.run for [maas.process_tags]
2019-02-22 14:10:52,977 [salt.utils.decorators:613 ][WARNING ][26362] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 14:10:53,022 [salt.loaded.ext.module.maas:92  ][INFO    ][26362] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-02-22 14:10:53,073 [salt.state       :300 ][INFO    ][26362] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-02-22 14:10:53,074 [salt.state       :1951][INFO    ][26362] Completed state [maas.process_tags] at time 14:10:53.074050 duration_in_ms=97.035
2019-02-22 14:10:53,077 [salt.minion      :1711][INFO    ][26362] Returning information for job: 20190222141029730723
2019-02-22 14:10:53,857 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222141053843899
2019-02-22 14:10:53,877 [salt.minion      :1432][INFO    ][26821] Starting a new job with PID 26821
2019-02-22 14:10:59,816 [salt.state       :915 ][INFO    ][26821] Loading fresh modules for state activity
2019-02-22 14:10:59,916 [salt.state       :1780][INFO    ][26821] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:10:59.915907
2019-02-22 14:10:59,916 [salt.state       :1813][INFO    ][26821] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 14:10:59,920 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26821] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 14:11:01,829 [salt.state       :300 ][INFO    ][26821] {'pid': 26857, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 14:11:01,831 [salt.state       :1951][INFO    ][26821] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:11:01.830839 duration_in_ms=1914.934
2019-02-22 14:11:01,833 [salt.state       :1780][INFO    ][26821] Running state [maas.process_machines] at time 14:11:01.833822
2019-02-22 14:11:01,834 [salt.state       :1813][INFO    ][26821] Executing state module.run for [maas.process_machines]
2019-02-22 14:11:01,835 [salt.utils.decorators:613 ][WARNING ][26821] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 14:11:02,394 [salt.loaded.ext.module.maas:412 ][WARNING ][26821] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 14:11:02,395 [salt.loaded.ext.module.maas:92  ][INFO    ][26821] 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=taw43g architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-22 14:11:03,538 [salt.loaded.ext.module.maas:412 ][WARNING ][26821] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 14:11:03,540 [salt.loaded.ext.module.maas:92  ][INFO    ][26821] 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=n7thfx architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-22 14:11:04,583 [salt.loaded.ext.module.maas:412 ][WARNING ][26821] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 14:11:04,584 [salt.loaded.ext.module.maas:92  ][INFO    ][26821] 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=nm8aet architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-22 14:11:05,506 [salt.loaded.ext.module.maas:412 ][WARNING ][26821] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 14:11:05,507 [salt.loaded.ext.module.maas:92  ][INFO    ][26821] 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=dkx8nr architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-22 14:11:06,506 [salt.loaded.ext.module.maas:412 ][WARNING ][26821] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 14:11:06,508 [salt.loaded.ext.module.maas:92  ][INFO    ][26821] 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=mqyp6q architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-22 14:11:07,430 [salt.state       :300 ][INFO    ][26821] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-02-22 14:11:07,431 [salt.state       :1951][INFO    ][26821] Completed state [maas.process_machines] at time 14:11:07.431468 duration_in_ms=5597.646
2019-02-22 14:11:07,434 [salt.minion      :1711][INFO    ][26821] Returning information for job: 20190222141053843899
2019-02-22 14:11:41,336 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222141141327207
2019-02-22 14:11:41,350 [salt.minion      :1432][INFO    ][27135] Starting a new job with PID 27135
2019-02-22 14:11:47,032 [salt.state       :915 ][INFO    ][27135] Loading fresh modules for state activity
2019-02-22 14:11:47,126 [salt.state       :1780][INFO    ][27135] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:11:47.126568
2019-02-22 14:11:47,127 [salt.state       :1813][INFO    ][27135] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 14:11:47,129 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27135] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 14:11:49,038 [salt.state       :300 ][INFO    ][27135] {'pid': 27146, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 14:11:49,038 [salt.state       :1951][INFO    ][27135] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:11:49.038629 duration_in_ms=1912.062
2019-02-22 14:11:49,041 [salt.state       :1780][INFO    ][27135] Running state [maas.wait_for_machine_status] at time 14:11:49.041207
2019-02-22 14:11:49,041 [salt.state       :1813][INFO    ][27135] Executing state module.run for [maas.wait_for_machine_status]
2019-02-22 14:11:49,041 [salt.utils.decorators:613 ][WARNING ][27135] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 14:11:51,386 [salt.state       :300 ][INFO    ][27135] {'ret': True}
2019-02-22 14:11:51,388 [salt.state       :1951][INFO    ][27135] Completed state [maas.wait_for_machine_status] at time 14:11:51.388142 duration_in_ms=2346.934
2019-02-22 14:11:51,390 [salt.minion      :1711][INFO    ][27135] Returning information for job: 20190222141141327207
2019-02-22 14:11:52,070 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222141152061134
2019-02-22 14:11:52,082 [salt.minion      :1432][INFO    ][27160] Starting a new job with PID 27160
2019-02-22 14:11:52,797 [salt.state       :915 ][INFO    ][27160] Loading fresh modules for state activity
2019-02-22 14:11:52,937 [salt.state       :1780][INFO    ][27160] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:11:52.937534
2019-02-22 14:11:52,937 [salt.state       :1813][INFO    ][27160] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 14:11:52,940 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27160] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 14:11:54,873 [salt.state       :300 ][INFO    ][27160] {'pid': 27167, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 14:11:54,873 [salt.state       :1951][INFO    ][27160] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:11:54.873632 duration_in_ms=1936.098
2019-02-22 14:11:54,876 [salt.state       :1780][INFO    ][27160] Running state [maas_machines_storage_cmp002_lvm] at time 14:11:54.876258
2019-02-22 14:11:54,876 [salt.state       :1813][INFO    ][27160] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-22 14:11:55,410 [salt.state       :300 ][INFO    ][27160] Machine cmp002 is not in Ready state.
2019-02-22 14:11:55,411 [salt.state       :1951][INFO    ][27160] Completed state [maas_machines_storage_cmp002_lvm] at time 14:11:55.411120 duration_in_ms=534.861
2019-02-22 14:11:55,411 [salt.state       :1780][INFO    ][27160] Running state [maas_machines_storage_cmp001_lvm] at time 14:11:55.411507
2019-02-22 14:11:55,411 [salt.state       :1813][INFO    ][27160] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-22 14:11:55,926 [salt.state       :300 ][INFO    ][27160] Machine cmp001 is not in Ready state.
2019-02-22 14:11:55,928 [salt.state       :1951][INFO    ][27160] Completed state [maas_machines_storage_cmp001_lvm] at time 14:11:55.927757 duration_in_ms=516.25
2019-02-22 14:11:55,930 [salt.minion      :1711][INFO    ][27160] Returning information for job: 20190222141152061134
2019-02-22 14:11:56,597 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222141156586310
2019-02-22 14:11:56,614 [salt.minion      :1432][INFO    ][27177] Starting a new job with PID 27177
2019-02-22 14:11:57,352 [salt.state       :915 ][INFO    ][27177] Loading fresh modules for state activity
2019-02-22 14:11:57,439 [salt.state       :1780][INFO    ][27177] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:11:57.439506
2019-02-22 14:11:57,439 [salt.state       :1813][INFO    ][27177] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 14:11:57,441 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27177] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 14:11:59,395 [salt.state       :300 ][INFO    ][27177] {'pid': 27184, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 14:11:59,396 [salt.state       :1951][INFO    ][27177] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:11:59.396202 duration_in_ms=1956.697
2019-02-22 14:11:59,397 [salt.state       :1780][INFO    ][27177] Running state [maas.deploy_machines] at time 14:11:59.397793
2019-02-22 14:11:59,398 [salt.state       :1813][INFO    ][27177] Executing state module.run for [maas.deploy_machines]
2019-02-22 14:11:59,399 [salt.utils.decorators:613 ][WARNING ][27177] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 14:11:59,865 [salt.loaded.ext.module.maas:684 ][INFO    ][27177] deploymachines hwe_kernel=hwe-16.04 system_id=mqyp6q distro_series=xenial
2019-02-22 14:12:01,735 [salt.state       :300 ][INFO    ][27177] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-02-22 14:12:01,735 [salt.state       :1951][INFO    ][27177] Completed state [maas.deploy_machines] at time 14:12:01.735910 duration_in_ms=2338.116
2019-02-22 14:12:01,738 [salt.minion      :1711][INFO    ][27177] Returning information for job: 20190222141156586310
2019-02-22 14:12:02,429 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command state.apply with jid 20190222141202419036
2019-02-22 14:12:02,442 [salt.minion      :1432][INFO    ][27257] Starting a new job with PID 27257
2019-02-22 14:12:03,172 [salt.state       :915 ][INFO    ][27257] Loading fresh modules for state activity
2019-02-22 14:12:03,257 [salt.state       :1780][INFO    ][27257] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:12:03.257556
2019-02-22 14:12:03,258 [salt.state       :1813][INFO    ][27257] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 14:12:03,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27257] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 14:12:05,186 [salt.state       :300 ][INFO    ][27257] {'pid': 27264, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 14:12:05,187 [salt.state       :1951][INFO    ][27257] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 14:12:05.187559 duration_in_ms=1930.003
2019-02-22 14:12:05,189 [salt.state       :1780][INFO    ][27257] Running state [maas.wait_for_machine_status] at time 14:12:05.189502
2019-02-22 14:12:05,189 [salt.state       :1813][INFO    ][27257] Executing state module.run for [maas.wait_for_machine_status]
2019-02-22 14:12:05,190 [salt.utils.decorators:613 ][WARNING ][27257] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 14:12:07,602 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.59784007s left)
2019-02-22 14:12:17,516 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141217462352
2019-02-22 14:12:17,534 [salt.minion      :1432][INFO    ][27310] Starting a new job with PID 27310
2019-02-22 14:12:17,566 [salt.minion      :1711][INFO    ][27310] Returning information for job: 20190222141217462352
2019-02-22 14:12:39,989 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2215.21159911s left)
2019-02-22 14:12:47,512 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141247504947
2019-02-22 14:12:47,528 [salt.minion      :1432][INFO    ][27359] Starting a new job with PID 27359
2019-02-22 14:12:47,556 [salt.minion      :1711][INFO    ][27359] Returning information for job: 20190222141247504947
2019-02-22 14:13:12,714 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2182.48625398s left)
2019-02-22 14:13:17,729 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141317720038
2019-02-22 14:13:17,745 [salt.minion      :1432][INFO    ][27418] Starting a new job with PID 27418
2019-02-22 14:13:17,779 [salt.minion      :1711][INFO    ][27418] Returning information for job: 20190222141317720038
2019-02-22 14:13:45,012 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2150.18764305s left)
2019-02-22 14:13:47,921 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141347912489
2019-02-22 14:13:47,937 [salt.minion      :1432][INFO    ][27464] Starting a new job with PID 27464
2019-02-22 14:13:47,965 [salt.minion      :1711][INFO    ][27464] Returning information for job: 20190222141347912489
2019-02-22 14:14:17,396 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2117.80405903s left)
2019-02-22 14:14:18,118 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141418109561
2019-02-22 14:14:18,133 [salt.minion      :1432][INFO    ][27525] Starting a new job with PID 27525
2019-02-22 14:14:18,169 [salt.minion      :1711][INFO    ][27525] Returning information for job: 20190222141418109561
2019-02-22 14:14:48,330 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141448322515
2019-02-22 14:14:48,346 [salt.minion      :1432][INFO    ][27563] Starting a new job with PID 27563
2019-02-22 14:14:48,374 [salt.minion      :1711][INFO    ][27563] Returning information for job: 20190222141448322515
2019-02-22 14:14:49,932 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2085.26797509s left)
2019-02-22 14:15:18,545 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141518536095
2019-02-22 14:15:18,562 [salt.minion      :1432][INFO    ][27638] Starting a new job with PID 27638
2019-02-22 14:15:18,602 [salt.minion      :1711][INFO    ][27638] Returning information for job: 20190222141518536095
2019-02-22 14:15:22,436 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2052.76447511s left)
2019-02-22 14:15:48,614 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141548606502
2019-02-22 14:15:48,629 [salt.minion      :1432][INFO    ][27684] Starting a new job with PID 27684
2019-02-22 14:15:48,656 [salt.minion      :1711][INFO    ][27684] Returning information for job: 20190222141548606502
2019-02-22 14:15:54,885 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2020.31550598s left)
2019-02-22 14:16:18,824 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141618812378
2019-02-22 14:16:18,842 [salt.minion      :1432][INFO    ][27770] Starting a new job with PID 27770
2019-02-22 14:16:18,874 [salt.minion      :1711][INFO    ][27770] Returning information for job: 20190222141618812378
2019-02-22 14:16:27,268 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1987.93228006s left)
2019-02-22 14:16:49,033 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141649025605
2019-02-22 14:16:49,050 [salt.minion      :1432][INFO    ][27843] Starting a new job with PID 27843
2019-02-22 14:16:49,078 [salt.minion      :1711][INFO    ][27843] Returning information for job: 20190222141649025605
2019-02-22 14:17:00,182 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1955.01789904s left)
2019-02-22 14:17:19,106 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141719099558
2019-02-22 14:17:19,118 [salt.minion      :1432][INFO    ][28151] Starting a new job with PID 28151
2019-02-22 14:17:19,148 [salt.minion      :1711][INFO    ][28151] Returning information for job: 20190222141719099558
2019-02-22 14:17:32,581 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1922.6187191s left)
2019-02-22 14:17:49,116 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141749109235
2019-02-22 14:17:49,128 [salt.minion      :1432][INFO    ][28228] Starting a new job with PID 28228
2019-02-22 14:17:49,160 [salt.minion      :1711][INFO    ][28228] Returning information for job: 20190222141749109235
2019-02-22 14:18:04,936 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1890.26367617s left)
2019-02-22 14:18:19,336 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141819327913
2019-02-22 14:18:19,349 [salt.minion      :1432][INFO    ][28327] Starting a new job with PID 28327
2019-02-22 14:18:19,382 [salt.minion      :1711][INFO    ][28327] Returning information for job: 20190222141819327913
2019-02-22 14:18:37,410 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1857.79000807s left)
2019-02-22 14:18:49,397 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141849378379
2019-02-22 14:18:49,416 [salt.minion      :1432][INFO    ][28372] Starting a new job with PID 28372
2019-02-22 14:18:49,449 [salt.minion      :1711][INFO    ][28372] Returning information for job: 20190222141849378379
2019-02-22 14:19:10,202 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1824.99784017s left)
2019-02-22 14:19:19,533 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141919520978
2019-02-22 14:19:19,556 [salt.minion      :1432][INFO    ][28515] Starting a new job with PID 28515
2019-02-22 14:19:19,591 [salt.minion      :1711][INFO    ][28515] Returning information for job: 20190222141919520978
2019-02-22 14:19:42,996 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1792.20451903s left)
2019-02-22 14:19:49,657 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222141949643523
2019-02-22 14:19:49,680 [salt.minion      :1432][INFO    ][28575] Starting a new job with PID 28575
2019-02-22 14:19:49,708 [salt.minion      :1711][INFO    ][28575] Returning information for job: 20190222141949643523
2019-02-22 14:20:15,748 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1759.45189214s left)
2019-02-22 14:20:19,785 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222142019776952
2019-02-22 14:20:19,810 [salt.minion      :1432][INFO    ][28657] Starting a new job with PID 28657
2019-02-22 14:20:19,841 [salt.minion      :1711][INFO    ][28657] Returning information for job: 20190222142019776952
2019-02-22 14:20:48,244 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1726.95635319s left)
2019-02-22 14:20:49,881 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222142049871986
2019-02-22 14:20:49,895 [salt.minion      :1432][INFO    ][28693] Starting a new job with PID 28693
2019-02-22 14:20:49,927 [salt.minion      :1711][INFO    ][28693] Returning information for job: 20190222142049871986
2019-02-22 14:21:19,896 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222142119885666
2019-02-22 14:21:19,915 [salt.minion      :1432][INFO    ][28757] Starting a new job with PID 28757
2019-02-22 14:21:19,943 [salt.minion      :1711][INFO    ][28757] Returning information for job: 20190222142119885666
2019-02-22 14:21:20,996 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1694.20412707s left)
2019-02-22 14:21:50,052 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222142150037980
2019-02-22 14:21:50,075 [salt.minion      :1432][INFO    ][28796] Starting a new job with PID 28796
2019-02-22 14:21:50,107 [salt.minion      :1711][INFO    ][28796] Returning information for job: 20190222142150037980
2019-02-22 14:21:53,764 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1661.43583798s left)
2019-02-22 14:22:20,194 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222142220186354
2019-02-22 14:22:20,215 [salt.minion      :1432][INFO    ][28861] Starting a new job with PID 28861
2019-02-22 14:22:20,250 [salt.minion      :1711][INFO    ][28861] Returning information for job: 20190222142220186354
2019-02-22 14:22:26,453 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1628.74703503s left)
2019-02-22 14:22:50,241 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222142250233445
2019-02-22 14:22:50,262 [salt.minion      :1432][INFO    ][28901] Starting a new job with PID 28901
2019-02-22 14:22:50,294 [salt.minion      :1711][INFO    ][28901] Returning information for job: 20190222142250233445
2019-02-22 14:22:58,852 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1596.34802699s left)
2019-02-22 14:23:20,405 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222142320389078
2019-02-22 14:23:20,424 [salt.minion      :1432][INFO    ][28966] Starting a new job with PID 28966
2019-02-22 14:23:20,453 [salt.minion      :1711][INFO    ][28966] Returning information for job: 20190222142320389078
2019-02-22 14:23:31,598 [salt.loaded.ext.module.maas:1023][INFO    ][27257] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1563.60233498s left)
2019-02-22 14:23:50,553 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222142350536779
2019-02-22 14:23:50,574 [salt.minion      :1432][INFO    ][29042] Starting a new job with PID 29042
2019-02-22 14:23:50,607 [salt.minion      :1711][INFO    ][29042] Returning information for job: 20190222142350536779
2019-02-22 14:24:04,129 [salt.state       :300 ][INFO    ][27257] {'ret': True}
2019-02-22 14:24:04,129 [salt.state       :1951][INFO    ][27257] Completed state [maas.wait_for_machine_status] at time 14:24:04.129593 duration_in_ms=718940.09
2019-02-22 14:24:04,133 [salt.minion      :1711][INFO    ][27257] Returning information for job: 20190222141202419036
2019-02-22 14:24:53,483 [salt.utils.schedule:1377][INFO    ][17473] Running scheduled job: __mine_interval
2019-02-22 15:24:53,483 [salt.utils.schedule:1377][INFO    ][17473] Running scheduled job: __mine_interval
2019-02-22 15:30:33,676 [salt.minion      :1308][INFO    ][17473] User sudo_ubuntu Executing command cp.push_dir with jid 20190222153033662451
2019-02-22 15:30:33,696 [salt.minion      :1432][INFO    ][36037] Starting a new job with PID 36037
