2019-05-10 09:13:51,116 [salt.utils.decorators:613 ][WARNING ][1969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 09:13:51,848 [salt.utils.decorators:613 ][WARNING ][1969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 09:13:54,838 [salt.loaded.int.states.file:2298][WARNING ][2406] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-10 09:14:26,127 [salt.state       :2022][WARNING ][2975] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-10 09:14:29,251 [salt.utils.decorators:613 ][WARNING ][2975] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 09:14:41,718 [salt.utils.decorators:613 ][WARNING ][2975] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 09:15:08,680 [salt.utils.decorators:613 ][WARNING ][2975] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 09:15:09,727 [salt.utils.decorators:613 ][WARNING ][2975] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 09:15:11,791 [salt.loaded.ext.module.maasng:1008][WARNING ][2975] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-10 09:15:11,791 [salt.loaded.ext.module.maasng:1011][WARNING ][2975] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-10 09:15:12,474 [salt.loaded.ext.module.maasng:1235][WARNING ][2975] Ignoring parameter vlan:0
2019-05-10 09:15:13,319 [salt.utils.decorators:613 ][WARNING ][2975] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 09:15:22,460 [salt.utils.decorators:613 ][WARNING ][5364] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 09:15:22,520 [salt.loaded.ext.module.maas:412 ][WARNING ][5364] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 09:15:24,046 [salt.loaded.ext.module.maas:412 ][WARNING ][5364] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 09:15:25,422 [salt.loaded.ext.module.maas:412 ][WARNING ][5364] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 09:15:26,771 [salt.loaded.ext.module.maas:412 ][WARNING ][5364] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 09:15:28,002 [salt.loaded.ext.module.maas:412 ][WARNING ][5364] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 09:15:33,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5993] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-10 09:15:33,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5993] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-10 09:15:33,628 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-05-10 09:15:34,651 [salt.cli.daemons :293 ][INFO    ][6045] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-05-10 09:15:34,774 [salt.cli.daemons :82  ][INFO    ][6045] Starting up the Salt Minion
2019-05-10 09:15:34,775 [salt.utils.event :1017][INFO    ][6045] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-05-10 09:15:35,971 [salt.minion      :976 ][INFO    ][6045] Creating minion process manager
2019-05-10 09:15:37,907 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6045] Executing command ['date', '+%z'] in directory '/root'
2019-05-10 09:15:37,935 [salt.utils.schedule:568 ][INFO    ][6045] Updating job settings for scheduled job: __mine_interval
2019-05-10 09:15:37,936 [salt.minion      :1108][INFO    ][6045] Added mine.update to scheduler
2019-05-10 09:15:37,942 [salt.minion      :1975][INFO    ][6045] Minion is starting as user 'root'
2019-05-10 09:15:37,961 [salt.minion      :2336][INFO    ][6045] Minion is ready to receive requests!
2019-05-10 09:16:00,295 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510091600280042
2019-05-10 09:16:00,325 [salt.minion      :1432][INFO    ][6133] Starting a new job with PID 6133
2019-05-10 09:16:06,342 [salt.state       :915 ][INFO    ][6133] Loading fresh modules for state activity
2019-05-10 09:16:06,400 [salt.fileclient  :1219][INFO    ][6133] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-10 09:16:06,451 [salt.state       :1780][INFO    ][6133] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:16:06.451478
2019-05-10 09:16:06,451 [salt.state       :1813][INFO    ][6133] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 09:16:06,453 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6133] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 09:16:08,335 [salt.state       :300 ][INFO    ][6133] {'pid': 6142, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 09:16:08,336 [salt.state       :1951][INFO    ][6133] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:16:08.336309 duration_in_ms=1884.83
2019-05-10 09:16:08,340 [salt.state       :1780][INFO    ][6133] Running state [maas.wait_for_machine_status] at time 09:16:08.340875
2019-05-10 09:16:08,341 [salt.state       :1813][INFO    ][6133] Executing state module.run for [maas.wait_for_machine_status]
2019-05-10 09:16:08,343 [salt.utils.decorators:613 ][WARNING ][6133] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 09:16:09,181 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.169945s left)
2019-05-10 09:16:15,426 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510091615409494
2019-05-10 09:16:15,452 [salt.minion      :1432][INFO    ][6153] Starting a new job with PID 6153
2019-05-10 09:16:15,473 [salt.minion      :1711][INFO    ][6153] Returning information for job: 20190510091615409494
2019-05-10 09:16:40,011 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.33974504s left)
2019-05-10 09:16:45,500 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510091645480729
2019-05-10 09:16:45,526 [salt.minion      :1432][INFO    ][6206] Starting a new job with PID 6206
2019-05-10 09:16:45,548 [salt.minion      :1711][INFO    ][6206] Returning information for job: 20190510091645480729
2019-05-10 09:17:10,865 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.48618007s left)
2019-05-10 09:17:15,599 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510091715577247
2019-05-10 09:17:15,634 [salt.minion      :1432][INFO    ][6242] Starting a new job with PID 6242
2019-05-10 09:17:15,664 [salt.minion      :1711][INFO    ][6242] Returning information for job: 20190510091715577247
2019-05-10 09:17:41,665 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.68605709s left)
2019-05-10 09:17:45,684 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510091745672106
2019-05-10 09:17:45,711 [salt.minion      :1432][INFO    ][6288] Starting a new job with PID 6288
2019-05-10 09:17:45,737 [salt.minion      :1711][INFO    ][6288] Returning information for job: 20190510091745672106
2019-05-10 09:18:12,724 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.627033s left)
2019-05-10 09:18:15,766 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510091815756275
2019-05-10 09:18:15,786 [salt.minion      :1432][INFO    ][6346] Starting a new job with PID 6346
2019-05-10 09:18:15,804 [salt.minion      :1711][INFO    ][6346] Returning information for job: 20190510091815756275
2019-05-10 09:18:43,820 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.53104115s left)
2019-05-10 09:18:45,868 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510091845854488
2019-05-10 09:18:45,886 [salt.minion      :1432][INFO    ][6491] Starting a new job with PID 6491
2019-05-10 09:18:45,910 [salt.minion      :1711][INFO    ][6491] Returning information for job: 20190510091845854488
2019-05-10 09:19:14,986 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.36469412s left)
2019-05-10 09:19:15,952 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510091915939447
2019-05-10 09:19:15,977 [salt.minion      :1432][INFO    ][6564] Starting a new job with PID 6564
2019-05-10 09:19:15,999 [salt.minion      :1711][INFO    ][6564] Returning information for job: 20190510091915939447
2019-05-10 09:19:46,076 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510091946064252
2019-05-10 09:19:46,091 [salt.minion      :1432][INFO    ][6897] Starting a new job with PID 6897
2019-05-10 09:19:46,115 [salt.minion      :1711][INFO    ][6897] Returning information for job: 20190510091946064252
2019-05-10 09:19:46,323 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1282.02838111s left)
2019-05-10 09:20:16,139 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092016126245
2019-05-10 09:20:16,165 [salt.minion      :1432][INFO    ][6982] Starting a new job with PID 6982
2019-05-10 09:20:16,188 [salt.minion      :1711][INFO    ][6982] Returning information for job: 20190510092016126245
2019-05-10 09:20:18,387 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.96367693s left)
2019-05-10 09:20:46,309 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092046296534
2019-05-10 09:20:46,337 [salt.minion      :1432][INFO    ][7344] Starting a new job with PID 7344
2019-05-10 09:20:46,358 [salt.minion      :1711][INFO    ][7344] Returning information for job: 20190510092046296534
2019-05-10 09:20:50,411 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.93994403s left)
2019-05-10 09:21:16,417 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092116408548
2019-05-10 09:21:16,443 [salt.minion      :1432][INFO    ][7500] Starting a new job with PID 7500
2019-05-10 09:21:16,466 [salt.minion      :1711][INFO    ][7500] Returning information for job: 20190510092116408548
2019-05-10 09:21:22,557 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.79362798s left)
2019-05-10 09:21:46,567 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092146554425
2019-05-10 09:21:46,600 [salt.minion      :1432][INFO    ][7678] Starting a new job with PID 7678
2019-05-10 09:21:46,632 [salt.minion      :1711][INFO    ][7678] Returning information for job: 20190510092146554425
2019-05-10 09:21:54,954 [salt.loaded.ext.module.maas:1023][INFO    ][6133] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.39688993s left)
2019-05-10 09:22:16,692 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092216683214
2019-05-10 09:22:16,717 [salt.minion      :1432][INFO    ][7819] Starting a new job with PID 7819
2019-05-10 09:22:16,748 [salt.minion      :1711][INFO    ][7819] Returning information for job: 20190510092216683214
2019-05-10 09:22:28,045 [salt.state       :300 ][INFO    ][6133] {'ret': True}
2019-05-10 09:22:28,047 [salt.state       :1951][INFO    ][6133] Completed state [maas.wait_for_machine_status] at time 09:22:28.047207 duration_in_ms=379706.333
2019-05-10 09:22:28,051 [salt.minion      :1711][INFO    ][6133] Returning information for job: 20190510091600280042
2019-05-10 09:22:28,757 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510092228741437
2019-05-10 09:22:28,784 [salt.minion      :1432][INFO    ][7848] Starting a new job with PID 7848
2019-05-10 09:22:34,986 [salt.state       :915 ][INFO    ][7848] Loading fresh modules for state activity
2019-05-10 09:22:35,047 [salt.fileclient  :1219][INFO    ][7848] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-10 09:22:35,151 [salt.state       :1780][INFO    ][7848] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:35.151805
2019-05-10 09:22:35,152 [salt.state       :1813][INFO    ][7848] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 09:22:35,154 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7848] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 09:22:37,076 [salt.state       :300 ][INFO    ][7848] {'pid': 7896, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 09:22:37,077 [salt.state       :1951][INFO    ][7848] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:37.077193 duration_in_ms=1925.388
2019-05-10 09:22:37,082 [salt.state       :1780][INFO    ][7848] Running state [maas_machines_storage_cmp002_lvm] at time 09:22:37.082532
2019-05-10 09:22:37,083 [salt.state       :1813][INFO    ][7848] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-10 09:22:38,264 [salt.loaded.ext.module.maasng:610 ][INFO    ][7848] kdx7ph
2019-05-10 09:22:38,265 [salt.loaded.ext.module.maasng:626 ][INFO    ][7848] sda
2019-05-10 09:22:38,840 [salt.loaded.ext.module.maasng:361 ][INFO    ][7848] kdx7ph
2019-05-10 09:22:38,939 [salt.loaded.ext.module.maasng:367 ][INFO    ][7848] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'kdx7ph', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'4f33073a-acfb-4ba4-9666-b8fb03c04db1', u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kdx7ph', u'filesystem': {u'mount_options': None, u'uuid': u'107105e6-88f8-49df-a3c4-5298ddeeaefd', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/blockdevices/3/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'uuid': u'27540a25-c938-4fab-b773-6efc950e4d57', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'd6e232dd-3d46-48b1-9321-8cdf2407ca5d', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kdx7ph', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'serial': None, u'partitions': []}]
2019-05-10 09:22:38,940 [salt.loaded.ext.module.maasng:632 ][INFO    ][7848] vgroot
2019-05-10 09:22:38,941 [salt.loaded.ext.module.maasng:635 ][INFO    ][7848] lvroot
2019-05-10 09:22:38,942 [salt.loaded.ext.module.maasng:639 ][INFO    ][7848] 107374182400
2019-05-10 09:22:39,565 [salt.loaded.ext.module.maasng:645 ][INFO    ][7848] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'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'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'73d6d418-bc7d-4c67-bed8-04f4aca8c67c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kdx7ph', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'1cfdeea4-8147-4d6b-a829-78c8ea54ec24', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'kdx7ph', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/blockdevices/1/'}, u'blockdevice_set': [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'73d6d418-bc7d-4c67-bed8-04f4aca8c67c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kdx7ph', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'1cfdeea4-8147-4d6b-a829-78c8ea54ec24', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'kdx7ph', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/blockdevices/1/'}, {u'size': 107374182400, u'uuid': u'e3a7867b-2776-4bf3-9215-eb6d92235f6d', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'kdx7ph', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'43ea073b-60e6-45ab-9c27-ffc859300988', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/blockdevices/11/'}], 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'cmp002', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'kdx7ph', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'e3a7867b-2776-4bf3-9215-eb6d92235f6d', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'kdx7ph', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'43ea073b-60e6-45ab-9c27-ffc859300988', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/blockdevices/11/'}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'kdx7ph', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', 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.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', 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.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kdx7ph', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/interfaces/4/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'73d6d418-bc7d-4c67-bed8-04f4aca8c67c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kdx7ph', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'1cfdeea4-8147-4d6b-a829-78c8ea54ec24', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'kdx7ph', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/blockdevices/1/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', 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.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', 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.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kdx7ph', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kdx7ph', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/interfaces/11/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kdx7ph', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kdx7ph', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/interfaces/14/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', 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': 21}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kdx7ph', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kdx7ph', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/kdx7ph/interfaces/12/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/kdx7ph/'}
2019-05-10 09:22:39,568 [salt.state       :300 ][INFO    ][7848] {'new': {'storage_layout': 'lvm'}}
2019-05-10 09:22:39,568 [salt.state       :1951][INFO    ][7848] Completed state [maas_machines_storage_cmp002_lvm] at time 09:22:39.568690 duration_in_ms=2486.157
2019-05-10 09:22:39,569 [salt.state       :1780][INFO    ][7848] Running state [maas_machines_storage_cmp001_lvm] at time 09:22:39.569433
2019-05-10 09:22:39,570 [salt.state       :1813][INFO    ][7848] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-10 09:22:40,715 [salt.loaded.ext.module.maasng:610 ][INFO    ][7848] mcdgqe
2019-05-10 09:22:40,715 [salt.loaded.ext.module.maasng:626 ][INFO    ][7848] sda
2019-05-10 09:22:41,281 [salt.loaded.ext.module.maasng:361 ][INFO    ][7848] mcdgqe
2019-05-10 09:22:41,379 [salt.loaded.ext.module.maasng:367 ][INFO    ][7848] [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'83b3aa52-cc53-4f0e-aa1f-efc32015dc92', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mcdgqe', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'918ece79-2955-466f-813e-0879095f431a', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'device_id': 2}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mcdgqe', 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'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/blockdevices/2/'}, {u'size': 800097042432, u'uuid': u'3f577036-547c-43cf-80f9-64e20edf8946', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'mcdgqe', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'f4261371-c6ad-4877-8b11-8912342960b3', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/blockdevices/4/'}]
2019-05-10 09:22:41,380 [salt.loaded.ext.module.maasng:632 ][INFO    ][7848] vgroot
2019-05-10 09:22:41,380 [salt.loaded.ext.module.maasng:635 ][INFO    ][7848] lvroot
2019-05-10 09:22:41,380 [salt.loaded.ext.module.maasng:639 ][INFO    ][7848] 107374182400
2019-05-10 09:22:41,989 [salt.loaded.ext.module.maasng:645 ][INFO    ][7848] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'storage_test_status_name': u'Passed', u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 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': 25}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'mcdgqe', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/blockdevices/2/', u'system_id': u'mcdgqe', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'86be1ac5-b3c1-4f7c-9a53-3adf28418b72', u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mcdgqe', u'filesystem': {u'mount_options': None, u'uuid': u'2bfb511a-c012-4e68-972e-66bfe2161171', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/mcdgqe/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'mcdgqe', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'24d27106-70e0-4805-889b-5929ff354c74', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'uuid': u'a960cfd7-75e4-4dd2-ac31-6e2f07d52906', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/blockdevices/12/', u'system_id': u'mcdgqe', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mcdgqe', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'86be1ac5-b3c1-4f7c-9a53-3adf28418b72', u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mcdgqe', u'filesystem': {u'mount_options': None, u'uuid': u'2bfb511a-c012-4e68-972e-66bfe2161171', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'uuid': u'a960cfd7-75e4-4dd2-ac31-6e2f07d52906', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'24d27106-70e0-4805-889b-5929ff354c74', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mcdgqe', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/blockdevices/2/', u'system_id': u'mcdgqe', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'86be1ac5-b3c1-4f7c-9a53-3adf28418b72', u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mcdgqe', u'filesystem': {u'mount_options': None, u'uuid': u'2bfb511a-c012-4e68-972e-66bfe2161171', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mcdgqe', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 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': 25}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'mcdgqe', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'system_id': u'mcdgqe', u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/interfaces/17/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'parents': [], u'system_id': u'mcdgqe', u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'system_id': u'mcdgqe', u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/interfaces/16/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'system_id': u'mcdgqe', u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/interfaces/19/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 28}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'id': 20, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'system_id': u'mcdgqe', u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mcdgqe/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-10 09:22:41,993 [salt.state       :300 ][INFO    ][7848] {'new': {'storage_layout': 'lvm'}}
2019-05-10 09:22:41,993 [salt.state       :1951][INFO    ][7848] Completed state [maas_machines_storage_cmp001_lvm] at time 09:22:41.993391 duration_in_ms=2423.958
2019-05-10 09:22:41,996 [salt.minion      :1711][INFO    ][7848] Returning information for job: 20190510092228741437
2019-05-10 09:22:42,703 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510092242691018
2019-05-10 09:22:42,730 [salt.minion      :1432][INFO    ][7914] Starting a new job with PID 7914
2019-05-10 09:22:43,744 [salt.state       :915 ][INFO    ][7914] Loading fresh modules for state activity
2019-05-10 09:22:43,809 [salt.fileclient  :1219][INFO    ][7914] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-10 09:22:43,863 [salt.state       :1780][INFO    ][7914] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:43.863376
2019-05-10 09:22:43,863 [salt.state       :1813][INFO    ][7914] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 09:22:43,865 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7914] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 09:22:45,680 [salt.state       :300 ][INFO    ][7914] {'pid': 7921, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 09:22:45,682 [salt.state       :1951][INFO    ][7914] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:22:45.682738 duration_in_ms=1819.363
2019-05-10 09:22:45,685 [salt.state       :1780][INFO    ][7914] Running state [maas.deploy_machines] at time 09:22:45.685492
2019-05-10 09:22:45,687 [salt.state       :1813][INFO    ][7914] Executing state module.run for [maas.deploy_machines]
2019-05-10 09:22:45,688 [salt.utils.decorators:613 ][WARNING ][7914] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 09:22:46,260 [salt.loaded.ext.module.maas:684 ][INFO    ][7914] deploymachines hwe_kernel=hwe-16.04 system_id=kdx7ph distro_series=xenial
2019-05-10 09:22:48,799 [salt.loaded.ext.module.maas:684 ][INFO    ][7914] deploymachines hwe_kernel=hwe-16.04 system_id=mcdgqe distro_series=xenial
2019-05-10 09:22:51,282 [salt.loaded.ext.module.maas:684 ][INFO    ][7914] deploymachines hwe_kernel=hwe-16.04 system_id=acnhww distro_series=xenial
2019-05-10 09:22:53,761 [salt.loaded.ext.module.maas:684 ][INFO    ][7914] deploymachines hwe_kernel=hwe-16.04 system_id=ahf78e distro_series=xenial
2019-05-10 09:22:56,348 [salt.loaded.ext.module.maas:684 ][INFO    ][7914] deploymachines hwe_kernel=hwe-16.04 system_id=qn474m distro_series=xenial
2019-05-10 09:22:57,816 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092257806822
2019-05-10 09:22:57,837 [salt.minion      :1432][INFO    ][8180] Starting a new job with PID 8180
2019-05-10 09:22:57,864 [salt.minion      :1711][INFO    ][8180] Returning information for job: 20190510092257806822
2019-05-10 09:22:59,046 [salt.state       :300 ][INFO    ][7914] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-10 09:22:59,046 [salt.state       :1951][INFO    ][7914] Completed state [maas.deploy_machines] at time 09:22:59.046539 duration_in_ms=13361.047
2019-05-10 09:22:59,049 [salt.minion      :1711][INFO    ][7914] Returning information for job: 20190510092242691018
2019-05-10 09:22:59,767 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510092259749621
2019-05-10 09:22:59,792 [salt.minion      :1432][INFO    ][8201] Starting a new job with PID 8201
2019-05-10 09:23:05,815 [salt.state       :915 ][INFO    ][8201] Loading fresh modules for state activity
2019-05-10 09:23:05,875 [salt.fileclient  :1219][INFO    ][8201] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-10 09:23:05,935 [salt.state       :1780][INFO    ][8201] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:23:05.935680
2019-05-10 09:23:05,936 [salt.state       :1813][INFO    ][8201] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 09:23:05,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8201] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 09:23:07,936 [salt.state       :300 ][INFO    ][8201] {'pid': 8222, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 09:23:07,938 [salt.state       :1951][INFO    ][8201] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:23:07.938627 duration_in_ms=2002.947
2019-05-10 09:23:07,942 [salt.state       :1780][INFO    ][8201] Running state [maas.wait_for_machine_status] at time 09:23:07.942159
2019-05-10 09:23:07,942 [salt.state       :1813][INFO    ][8201] Executing state module.run for [maas.wait_for_machine_status]
2019-05-10 09:23:07,943 [salt.utils.decorators:613 ][WARNING ][8201] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 09:23:10,747 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.21226501s left)
2019-05-10 09:23:14,880 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092314869640
2019-05-10 09:23:14,905 [salt.minion      :1432][INFO    ][8237] Starting a new job with PID 8237
2019-05-10 09:23:14,937 [salt.minion      :1711][INFO    ][8237] Returning information for job: 20190510092314869640
2019-05-10 09:23:43,635 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.32367992s left)
2019-05-10 09:23:44,976 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092344962557
2019-05-10 09:23:45,006 [salt.minion      :1432][INFO    ][8296] Starting a new job with PID 8296
2019-05-10 09:23:45,034 [salt.minion      :1711][INFO    ][8296] Returning information for job: 20190510092344962557
2019-05-10 09:24:15,052 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092415041291
2019-05-10 09:24:15,069 [salt.minion      :1432][INFO    ][8324] Starting a new job with PID 8324
2019-05-10 09:24:15,100 [salt.minion      :1711][INFO    ][8324] Returning information for job: 20190510092415041291
2019-05-10 09:24:16,548 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.41062498s left)
2019-05-10 09:24:45,137 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092445121614
2019-05-10 09:24:45,168 [salt.minion      :1432][INFO    ][8396] Starting a new job with PID 8396
2019-05-10 09:24:45,195 [salt.minion      :1711][INFO    ][8396] Returning information for job: 20190510092445121614
2019-05-10 09:24:49,386 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.57322502s left)
2019-05-10 09:25:15,239 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092515224175
2019-05-10 09:25:15,268 [salt.minion      :1432][INFO    ][8420] Starting a new job with PID 8420
2019-05-10 09:25:15,296 [salt.minion      :1711][INFO    ][8420] Returning information for job: 20190510092515224175
2019-05-10 09:25:22,156 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.80349898s left)
2019-05-10 09:25:45,332 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092545318834
2019-05-10 09:25:45,361 [salt.minion      :1432][INFO    ][8494] Starting a new job with PID 8494
2019-05-10 09:25:45,419 [salt.minion      :1711][INFO    ][8494] Returning information for job: 20190510092545318834
2019-05-10 09:25:55,256 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.70261693s left)
2019-05-10 09:26:15,479 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092615464970
2019-05-10 09:26:15,509 [salt.minion      :1432][INFO    ][8562] Starting a new job with PID 8562
2019-05-10 09:26:15,538 [salt.minion      :1711][INFO    ][8562] Returning information for job: 20190510092615464970
2019-05-10 09:26:28,235 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.72401905s left)
2019-05-10 09:26:45,608 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092645596328
2019-05-10 09:26:45,635 [salt.minion      :1432][INFO    ][8823] Starting a new job with PID 8823
2019-05-10 09:26:45,669 [salt.minion      :1711][INFO    ][8823] Returning information for job: 20190510092645596328
2019-05-10 09:27:01,243 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.71626496s left)
2019-05-10 09:27:15,719 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092715708488
2019-05-10 09:27:15,738 [salt.minion      :1432][INFO    ][8897] Starting a new job with PID 8897
2019-05-10 09:27:15,766 [salt.minion      :1711][INFO    ][8897] Returning information for job: 20190510092715708488
2019-05-10 09:27:34,035 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.92377996s left)
2019-05-10 09:27:45,857 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092745840341
2019-05-10 09:27:45,887 [salt.minion      :1432][INFO    ][9147] Starting a new job with PID 9147
2019-05-10 09:27:45,922 [salt.minion      :1711][INFO    ][9147] Returning information for job: 20190510092745840341
2019-05-10 09:28:07,108 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.85123396s left)
2019-05-10 09:28:16,005 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092815987823
2019-05-10 09:28:16,033 [salt.minion      :1432][INFO    ][9213] Starting a new job with PID 9213
2019-05-10 09:28:16,062 [salt.minion      :1711][INFO    ][9213] Returning information for job: 20190510092815987823
2019-05-10 09:28:39,893 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1918.06640887s left)
2019-05-10 09:28:46,193 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092846179474
2019-05-10 09:28:46,223 [salt.minion      :1432][INFO    ][9616] Starting a new job with PID 9616
2019-05-10 09:28:46,251 [salt.minion      :1711][INFO    ][9616] Returning information for job: 20190510092846179474
2019-05-10 09:29:12,961 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.998384s left)
2019-05-10 09:29:16,340 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092916327401
2019-05-10 09:29:16,367 [salt.minion      :1432][INFO    ][9653] Starting a new job with PID 9653
2019-05-10 09:29:16,395 [salt.minion      :1711][INFO    ][9653] Returning information for job: 20190510092916327401
2019-05-10 09:29:45,929 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1852.02980399s left)
2019-05-10 09:29:46,539 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510092946529996
2019-05-10 09:29:46,559 [salt.minion      :1432][INFO    ][10079] Starting a new job with PID 10079
2019-05-10 09:29:46,590 [salt.minion      :1711][INFO    ][10079] Returning information for job: 20190510092946529996
2019-05-10 09:30:16,685 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093016672579
2019-05-10 09:30:16,710 [salt.minion      :1432][INFO    ][10118] Starting a new job with PID 10118
2019-05-10 09:30:16,739 [salt.minion      :1711][INFO    ][10118] Returning information for job: 20190510093016672579
2019-05-10 09:30:18,863 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1819.0964911s left)
2019-05-10 09:30:46,880 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093046867078
2019-05-10 09:30:46,908 [salt.minion      :1432][INFO    ][10336] Starting a new job with PID 10336
2019-05-10 09:30:46,941 [salt.minion      :1711][INFO    ][10336] Returning information for job: 20190510093046867078
2019-05-10 09:30:51,749 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1786.21049309s left)
2019-05-10 09:31:17,061 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093117041576
2019-05-10 09:31:17,092 [salt.minion      :1432][INFO    ][10394] Starting a new job with PID 10394
2019-05-10 09:31:17,129 [salt.minion      :1711][INFO    ][10394] Returning information for job: 20190510093117041576
2019-05-10 09:31:24,822 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1753.13665295s left)
2019-05-10 09:31:47,285 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093147269244
2019-05-10 09:31:47,316 [salt.minion      :1432][INFO    ][10648] Starting a new job with PID 10648
2019-05-10 09:31:47,357 [salt.minion      :1711][INFO    ][10648] Returning information for job: 20190510093147269244
2019-05-10 09:31:57,791 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.16840506s left)
2019-05-10 09:32:17,416 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093217401211
2019-05-10 09:32:17,442 [salt.minion      :1432][INFO    ][10713] Starting a new job with PID 10713
2019-05-10 09:32:17,478 [salt.minion      :1711][INFO    ][10713] Returning information for job: 20190510093217401211
2019-05-10 09:32:31,823 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.13642097s left)
2019-05-10 09:32:47,625 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093247610914
2019-05-10 09:32:47,651 [salt.minion      :1432][INFO    ][10899] Starting a new job with PID 10899
2019-05-10 09:32:47,685 [salt.minion      :1711][INFO    ][10899] Returning information for job: 20190510093247610914
2019-05-10 09:33:04,691 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1653.26831794s left)
2019-05-10 09:33:17,832 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093317821004
2019-05-10 09:33:17,861 [salt.minion      :1432][INFO    ][10953] Starting a new job with PID 10953
2019-05-10 09:33:17,892 [salt.minion      :1711][INFO    ][10953] Returning information for job: 20190510093317821004
2019-05-10 09:33:37,559 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1620.39954305s left)
2019-05-10 09:33:48,046 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093348031711
2019-05-10 09:33:48,073 [salt.minion      :1432][INFO    ][11116] Starting a new job with PID 11116
2019-05-10 09:33:48,107 [salt.minion      :1711][INFO    ][11116] Returning information for job: 20190510093348031711
2019-05-10 09:34:10,700 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1587.25949597s left)
2019-05-10 09:34:18,240 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093418228402
2019-05-10 09:34:18,265 [salt.minion      :1432][INFO    ][11276] Starting a new job with PID 11276
2019-05-10 09:34:18,298 [salt.minion      :1711][INFO    ][11276] Returning information for job: 20190510093418228402
2019-05-10 09:34:43,601 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1554.35776401s left)
2019-05-10 09:34:48,447 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093448431358
2019-05-10 09:34:48,476 [salt.minion      :1432][INFO    ][11337] Starting a new job with PID 11337
2019-05-10 09:34:48,515 [salt.minion      :1711][INFO    ][11337] Returning information for job: 20190510093448431358
2019-05-10 09:35:16,480 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1521.47857308s left)
2019-05-10 09:35:18,648 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093518635289
2019-05-10 09:35:18,668 [salt.minion      :1432][INFO    ][11369] Starting a new job with PID 11369
2019-05-10 09:35:18,699 [salt.minion      :1711][INFO    ][11369] Returning information for job: 20190510093518635289
2019-05-10 09:35:48,665 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093548653273
2019-05-10 09:35:48,682 [salt.minion      :1432][INFO    ][11454] Starting a new job with PID 11454
2019-05-10 09:35:48,715 [salt.minion      :1711][INFO    ][11454] Returning information for job: 20190510093548653273
2019-05-10 09:35:49,379 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1488.58022594s left)
2019-05-10 09:36:18,860 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093618848826
2019-05-10 09:36:18,888 [salt.minion      :1432][INFO    ][11536] Starting a new job with PID 11536
2019-05-10 09:36:18,920 [salt.minion      :1711][INFO    ][11536] Returning information for job: 20190510093618848826
2019-05-10 09:36:22,055 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1455.90409994s left)
2019-05-10 09:36:48,928 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093648911767
2019-05-10 09:36:48,959 [salt.minion      :1432][INFO    ][11789] Starting a new job with PID 11789
2019-05-10 09:36:48,990 [salt.minion      :1711][INFO    ][11789] Returning information for job: 20190510093648911767
2019-05-10 09:36:55,074 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1422.88545489s left)
2019-05-10 09:37:18,974 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093718961293
2019-05-10 09:37:19,004 [salt.minion      :1432][INFO    ][11818] Starting a new job with PID 11818
2019-05-10 09:37:19,036 [salt.minion      :1711][INFO    ][11818] Returning information for job: 20190510093718961293
2019-05-10 09:37:27,983 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1389.97613502s left)
2019-05-10 09:37:49,013 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093748996065
2019-05-10 09:37:49,043 [salt.minion      :1432][INFO    ][11866] Starting a new job with PID 11866
2019-05-10 09:37:49,076 [salt.minion      :1711][INFO    ][11866] Returning information for job: 20190510093748996065
2019-05-10 09:38:01,004 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1356.95533395s left)
2019-05-10 09:38:19,088 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093819076065
2019-05-10 09:38:19,112 [salt.minion      :1432][INFO    ][11899] Starting a new job with PID 11899
2019-05-10 09:38:19,146 [salt.minion      :1711][INFO    ][11899] Returning information for job: 20190510093819076065
2019-05-10 09:38:33,810 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1324.14923191s left)
2019-05-10 09:38:49,164 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093849148158
2019-05-10 09:38:49,192 [salt.minion      :1432][INFO    ][11951] Starting a new job with PID 11951
2019-05-10 09:38:49,218 [salt.minion      :1711][INFO    ][11951] Returning information for job: 20190510093849148158
2019-05-10 09:39:06,607 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1291.35233688s left)
2019-05-10 09:39:19,259 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093919247322
2019-05-10 09:39:19,288 [salt.minion      :1432][INFO    ][11980] Starting a new job with PID 11980
2019-05-10 09:39:19,319 [salt.minion      :1711][INFO    ][11980] Returning information for job: 20190510093919247322
2019-05-10 09:39:39,425 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1258.53406906s left)
2019-05-10 09:39:49,355 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510093949344918
2019-05-10 09:39:49,385 [salt.minion      :1432][INFO    ][12034] Starting a new job with PID 12034
2019-05-10 09:39:49,420 [salt.minion      :1711][INFO    ][12034] Returning information for job: 20190510093949344918
2019-05-10 09:40:12,202 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1225.75695205s left)
2019-05-10 09:40:19,469 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094019457477
2019-05-10 09:40:19,497 [salt.minion      :1432][INFO    ][12063] Starting a new job with PID 12063
2019-05-10 09:40:19,526 [salt.minion      :1711][INFO    ][12063] Returning information for job: 20190510094019457477
2019-05-10 09:40:44,992 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1192.96657395s left)
2019-05-10 09:40:49,559 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094049547308
2019-05-10 09:40:49,591 [salt.minion      :1432][INFO    ][12115] Starting a new job with PID 12115
2019-05-10 09:40:49,620 [salt.minion      :1711][INFO    ][12115] Returning information for job: 20190510094049547308
2019-05-10 09:41:17,752 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1160.20688105s left)
2019-05-10 09:41:19,705 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094119690508
2019-05-10 09:41:19,733 [salt.minion      :1432][INFO    ][12147] Starting a new job with PID 12147
2019-05-10 09:41:19,767 [salt.minion      :1711][INFO    ][12147] Returning information for job: 20190510094119690508
2019-05-10 09:41:49,860 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094149851213
2019-05-10 09:41:49,877 [salt.minion      :1432][INFO    ][12196] Starting a new job with PID 12196
2019-05-10 09:41:49,905 [salt.minion      :1711][INFO    ][12196] Returning information for job: 20190510094149851213
2019-05-10 09:41:50,593 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1127.36588788s left)
2019-05-10 09:42:19,989 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094219977038
2019-05-10 09:42:20,015 [salt.minion      :1432][INFO    ][12220] Starting a new job with PID 12220
2019-05-10 09:42:20,047 [salt.minion      :1711][INFO    ][12220] Returning information for job: 20190510094219977038
2019-05-10 09:42:23,410 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1094.54872489s left)
2019-05-10 09:42:50,166 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094250156633
2019-05-10 09:42:50,193 [salt.minion      :1432][INFO    ][12267] Starting a new job with PID 12267
2019-05-10 09:42:50,227 [salt.minion      :1711][INFO    ][12267] Returning information for job: 20190510094250156633
2019-05-10 09:42:56,214 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1061.74541903s left)
2019-05-10 09:43:20,349 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094320332863
2019-05-10 09:43:20,376 [salt.minion      :1432][INFO    ][12298] Starting a new job with PID 12298
2019-05-10 09:43:20,409 [salt.minion      :1711][INFO    ][12298] Returning information for job: 20190510094320332863
2019-05-10 09:43:29,011 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1028.9475069s left)
2019-05-10 09:43:50,567 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094350551002
2019-05-10 09:43:50,592 [salt.minion      :1432][INFO    ][12345] Starting a new job with PID 12345
2019-05-10 09:43:50,626 [salt.minion      :1711][INFO    ][12345] Returning information for job: 20190510094350551002
2019-05-10 09:44:01,714 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (996.244823933s left)
2019-05-10 09:44:20,777 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094420763032
2019-05-10 09:44:20,803 [salt.minion      :1432][INFO    ][12385] Starting a new job with PID 12385
2019-05-10 09:44:20,833 [salt.minion      :1711][INFO    ][12385] Returning information for job: 20190510094420763032
2019-05-10 09:44:34,423 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (963.536155939s left)
2019-05-10 09:44:50,807 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094450793312
2019-05-10 09:44:50,833 [salt.minion      :1432][INFO    ][12448] Starting a new job with PID 12448
2019-05-10 09:44:50,866 [salt.minion      :1711][INFO    ][12448] Returning information for job: 20190510094450793312
2019-05-10 09:45:07,085 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (930.873878002s left)
2019-05-10 09:45:21,018 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094521002385
2019-05-10 09:45:21,042 [salt.minion      :1432][INFO    ][12483] Starting a new job with PID 12483
2019-05-10 09:45:21,072 [salt.minion      :1711][INFO    ][12483] Returning information for job: 20190510094521002385
2019-05-10 09:45:40,063 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (897.895828962s left)
2019-05-10 09:45:51,089 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094551074874
2019-05-10 09:45:51,114 [salt.minion      :1432][INFO    ][12528] Starting a new job with PID 12528
2019-05-10 09:45:51,147 [salt.minion      :1711][INFO    ][12528] Returning information for job: 20190510094551074874
2019-05-10 09:46:12,730 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (865.229752064s left)
2019-05-10 09:46:21,148 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094621132884
2019-05-10 09:46:21,174 [salt.minion      :1432][INFO    ][12559] Starting a new job with PID 12559
2019-05-10 09:46:21,202 [salt.minion      :1711][INFO    ][12559] Returning information for job: 20190510094621132884
2019-05-10 09:46:45,669 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (832.290215015s left)
2019-05-10 09:46:51,257 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094651239385
2019-05-10 09:46:51,284 [salt.minion      :1432][INFO    ][12605] Starting a new job with PID 12605
2019-05-10 09:46:51,312 [salt.minion      :1711][INFO    ][12605] Returning information for job: 20190510094651239385
2019-05-10 09:47:18,340 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (799.618592978s left)
2019-05-10 09:47:21,360 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094721347719
2019-05-10 09:47:21,383 [salt.minion      :1432][INFO    ][12634] Starting a new job with PID 12634
2019-05-10 09:47:21,413 [salt.minion      :1711][INFO    ][12634] Returning information for job: 20190510094721347719
2019-05-10 09:47:51,192 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (766.767227888s left)
2019-05-10 09:47:51,476 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094751465211
2019-05-10 09:47:51,505 [salt.minion      :1432][INFO    ][12684] Starting a new job with PID 12684
2019-05-10 09:47:51,539 [salt.minion      :1711][INFO    ][12684] Returning information for job: 20190510094751465211
2019-05-10 09:48:21,640 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094821629920
2019-05-10 09:48:21,665 [salt.minion      :1432][INFO    ][12715] Starting a new job with PID 12715
2019-05-10 09:48:21,700 [salt.minion      :1711][INFO    ][12715] Returning information for job: 20190510094821629920
2019-05-10 09:48:24,113 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (733.846286058s left)
2019-05-10 09:48:51,778 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094851764161
2019-05-10 09:48:51,805 [salt.minion      :1432][INFO    ][12760] Starting a new job with PID 12760
2019-05-10 09:48:51,839 [salt.minion      :1711][INFO    ][12760] Returning information for job: 20190510094851764161
2019-05-10 09:48:56,875 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (701.08463192s left)
2019-05-10 09:49:21,805 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094921791548
2019-05-10 09:49:21,832 [salt.minion      :1432][INFO    ][12788] Starting a new job with PID 12788
2019-05-10 09:49:21,860 [salt.minion      :1711][INFO    ][12788] Returning information for job: 20190510094921791548
2019-05-10 09:49:29,733 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (668.226336956s left)
2019-05-10 09:49:52,008 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510094951996016
2019-05-10 09:49:52,039 [salt.minion      :1432][INFO    ][12838] Starting a new job with PID 12838
2019-05-10 09:49:52,071 [salt.minion      :1711][INFO    ][12838] Returning information for job: 20190510094951996016
2019-05-10 09:50:02,465 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (635.493948936s left)
2019-05-10 09:50:22,043 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095022026758
2019-05-10 09:50:22,074 [salt.minion      :1432][INFO    ][12869] Starting a new job with PID 12869
2019-05-10 09:50:22,112 [salt.minion      :1711][INFO    ][12869] Returning information for job: 20190510095022026758
2019-05-10 09:50:35,469 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (602.49048996s left)
2019-05-10 09:50:52,092 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095052076603
2019-05-10 09:50:52,120 [salt.minion      :1432][INFO    ][12919] Starting a new job with PID 12919
2019-05-10 09:50:52,157 [salt.minion      :1711][INFO    ][12919] Returning information for job: 20190510095052076603
2019-05-10 09:51:08,299 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (569.660403013s left)
2019-05-10 09:51:22,191 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095122175886
2019-05-10 09:51:22,219 [salt.minion      :1432][INFO    ][12950] Starting a new job with PID 12950
2019-05-10 09:51:22,256 [salt.minion      :1711][INFO    ][12950] Returning information for job: 20190510095122175886
2019-05-10 09:51:41,016 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (536.943310976s left)
2019-05-10 09:51:52,242 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095152228195
2019-05-10 09:51:52,271 [salt.minion      :1432][INFO    ][12999] Starting a new job with PID 12999
2019-05-10 09:51:52,308 [salt.minion      :1711][INFO    ][12999] Returning information for job: 20190510095152228195
2019-05-10 09:52:13,962 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (503.997127056s left)
2019-05-10 09:52:22,351 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095222334273
2019-05-10 09:52:22,380 [salt.minion      :1432][INFO    ][13026] Starting a new job with PID 13026
2019-05-10 09:52:22,411 [salt.minion      :1711][INFO    ][13026] Returning information for job: 20190510095222334273
2019-05-10 09:52:46,732 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (471.226892948s left)
2019-05-10 09:52:52,432 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095252417161
2019-05-10 09:52:52,458 [salt.minion      :1432][INFO    ][13073] Starting a new job with PID 13073
2019-05-10 09:52:52,488 [salt.minion      :1711][INFO    ][13073] Returning information for job: 20190510095252417161
2019-05-10 09:53:19,563 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (438.396365881s left)
2019-05-10 09:53:22,595 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095322582422
2019-05-10 09:53:22,619 [salt.minion      :1432][INFO    ][13103] Starting a new job with PID 13103
2019-05-10 09:53:22,652 [salt.minion      :1711][INFO    ][13103] Returning information for job: 20190510095322582422
2019-05-10 09:53:52,440 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (405.519288063s left)
2019-05-10 09:53:52,775 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095352763586
2019-05-10 09:53:52,796 [salt.minion      :1432][INFO    ][13152] Starting a new job with PID 13152
2019-05-10 09:53:52,828 [salt.minion      :1711][INFO    ][13152] Returning information for job: 20190510095352763586
2019-05-10 09:54:22,995 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095422979952
2019-05-10 09:54:23,017 [salt.minion      :1432][INFO    ][13308] Starting a new job with PID 13308
2019-05-10 09:54:23,050 [salt.minion      :1711][INFO    ][13308] Returning information for job: 20190510095422979952
2019-05-10 09:54:25,341 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (372.618128061s left)
2019-05-10 09:54:53,214 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095453198804
2019-05-10 09:54:53,241 [salt.minion      :1432][INFO    ][13368] Starting a new job with PID 13368
2019-05-10 09:54:53,276 [salt.minion      :1711][INFO    ][13368] Returning information for job: 20190510095453198804
2019-05-10 09:54:58,081 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (339.878040075s left)
2019-05-10 09:55:23,429 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095523413511
2019-05-10 09:55:23,455 [salt.minion      :1432][INFO    ][13404] Starting a new job with PID 13404
2019-05-10 09:55:23,488 [salt.minion      :1711][INFO    ][13404] Returning information for job: 20190510095523413511
2019-05-10 09:55:31,030 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (306.928525925s left)
2019-05-10 09:55:53,487 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095553473195
2019-05-10 09:55:53,515 [salt.minion      :1432][INFO    ][13450] Starting a new job with PID 13450
2019-05-10 09:55:53,554 [salt.minion      :1711][INFO    ][13450] Returning information for job: 20190510095553473195
2019-05-10 09:56:03,872 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (274.087195873s left)
2019-05-10 09:56:23,532 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095623516765
2019-05-10 09:56:23,557 [salt.minion      :1432][INFO    ][13479] Starting a new job with PID 13479
2019-05-10 09:56:23,594 [salt.minion      :1711][INFO    ][13479] Returning information for job: 20190510095623516765
2019-05-10 09:56:36,712 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (241.247190952s left)
2019-05-10 09:56:53,623 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095653613403
2019-05-10 09:56:53,650 [salt.minion      :1432][INFO    ][13526] Starting a new job with PID 13526
2019-05-10 09:56:53,683 [salt.minion      :1711][INFO    ][13526] Returning information for job: 20190510095653613403
2019-05-10 09:57:09,586 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (208.372723103s left)
2019-05-10 09:57:23,685 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095723669567
2019-05-10 09:57:23,710 [salt.minion      :1432][INFO    ][13556] Starting a new job with PID 13556
2019-05-10 09:57:23,740 [salt.minion      :1711][INFO    ][13556] Returning information for job: 20190510095723669567
2019-05-10 09:57:42,340 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (175.619399071s left)
2019-05-10 09:57:53,809 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095753792733
2019-05-10 09:57:53,837 [salt.minion      :1432][INFO    ][13605] Starting a new job with PID 13605
2019-05-10 09:57:53,871 [salt.minion      :1711][INFO    ][13605] Returning information for job: 20190510095753792733
2019-05-10 09:58:15,185 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (142.773566961s left)
2019-05-10 09:58:23,872 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095823860226
2019-05-10 09:58:23,893 [salt.minion      :1432][INFO    ][13638] Starting a new job with PID 13638
2019-05-10 09:58:23,928 [salt.minion      :1711][INFO    ][13638] Returning information for job: 20190510095823860226
2019-05-10 09:58:48,055 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (109.903527975s left)
2019-05-10 09:58:54,043 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095854025425
2019-05-10 09:58:54,070 [salt.minion      :1432][INFO    ][13687] Starting a new job with PID 13687
2019-05-10 09:58:54,098 [salt.minion      :1711][INFO    ][13687] Returning information for job: 20190510095854025425
2019-05-10 09:59:20,923 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (77.0362639427s left)
2019-05-10 09:59:24,174 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095924161417
2019-05-10 09:59:24,196 [salt.minion      :1432][INFO    ][13718] Starting a new job with PID 13718
2019-05-10 09:59:24,231 [salt.minion      :1711][INFO    ][13718] Returning information for job: 20190510095924161417
2019-05-10 09:59:53,793 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (44.1659660339s left)
2019-05-10 09:59:54,343 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510095954326909
2019-05-10 09:59:54,366 [salt.minion      :1432][INFO    ][13769] Starting a new job with PID 13769
2019-05-10 09:59:54,393 [salt.minion      :1711][INFO    ][13769] Returning information for job: 20190510095954326909
2019-05-10 10:00:24,376 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100024360631
2019-05-10 10:00:24,399 [salt.minion      :1432][INFO    ][13795] Starting a new job with PID 13795
2019-05-10 10:00:24,433 [salt.minion      :1711][INFO    ][13795] Returning information for job: 20190510100024360631
2019-05-10 10:00:26,583 [salt.loaded.ext.module.maas:1023][INFO    ][8201] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (11.3768000603s left)
2019-05-10 10:00:54,572 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100054557043
2019-05-10 10:00:54,596 [salt.minion      :1432][INFO    ][13841] Starting a new job with PID 13841
2019-05-10 10:00:54,629 [salt.minion      :1711][INFO    ][13841] Returning information for job: 20190510100054557043
2019-05-10 10:00:59,453 [salt.state       :302 ][ERROR   ][8201] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-10 10:00:59,454 [salt.state       :1951][INFO    ][8201] Completed state [maas.wait_for_machine_status] at time 10:00:59.454295 duration_in_ms=2271512.134
2019-05-10 10:00:59,461 [salt.minion      :1711][INFO    ][8201] Returning information for job: 20190510092259749621
2019-05-10 10:01:10,458 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command pillar.get with jid 20190510100110446252
2019-05-10 10:01:10,487 [salt.minion      :1432][INFO    ][13863] Starting a new job with PID 13863
2019-05-10 10:01:10,497 [salt.minion      :1711][INFO    ][13863] Returning information for job: 20190510100110446252
2019-05-10 10:01:11,188 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command service.status with jid 20190510100111176043
2019-05-10 10:01:11,215 [salt.minion      :1432][INFO    ][13868] Starting a new job with PID 13868
2019-05-10 10:01:11,972 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13868] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-10 10:01:12,011 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13868] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-10 10:01:12,033 [salt.minion      :1711][INFO    ][13868] Returning information for job: 20190510100111176043
2019-05-10 10:01:12,729 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510100112718446
2019-05-10 10:01:12,756 [salt.minion      :1432][INFO    ][13879] Starting a new job with PID 13879
2019-05-10 10:01:18,753 [salt.state       :915 ][INFO    ][13879] Loading fresh modules for state activity
2019-05-10 10:01:19,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command 'salt-minion --version' in directory '/root'
2019-05-10 10:01:19,798 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command 'salt-minion --version' in directory '/root'
2019-05-10 10:01:20,875 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command 'salt-minion --version' in directory '/root'
2019-05-10 10:01:21,219 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command 'salt-minion --version' in directory '/root'
2019-05-10 10:01:23,413 [salt.state       :1780][INFO    ][13879] Running state [salt-minion] at time 10:01:23.413133
2019-05-10 10:01:23,413 [salt.state       :1813][INFO    ][13879] Executing state pkg.installed for [salt-minion]
2019-05-10 10:01:23,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-10 10:01:23,547 [salt.state       :300 ][INFO    ][13879] All specified packages are already installed
2019-05-10 10:01:23,547 [salt.state       :1951][INFO    ][13879] Completed state [salt-minion] at time 10:01:23.547441 duration_in_ms=134.308
2019-05-10 10:01:23,547 [salt.state       :1780][INFO    ][13879] Running state [salt_minion_dependency_packages] at time 10:01:23.547820
2019-05-10 10:01:23,548 [salt.state       :1813][INFO    ][13879] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-10 10:01:23,561 [salt.state       :300 ][INFO    ][13879] All specified packages are already installed
2019-05-10 10:01:23,562 [salt.state       :1951][INFO    ][13879] Completed state [salt_minion_dependency_packages] at time 10:01:23.561915 duration_in_ms=14.094
2019-05-10 10:01:23,566 [salt.state       :1780][INFO    ][13879] Running state [/etc/salt/minion.d/minion.conf] at time 10:01:23.566216
2019-05-10 10:01:23,566 [salt.state       :1813][INFO    ][13879] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-10 10:01:23,849 [salt.state       :300 ][INFO    ][13879] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-10 10:01:23,850 [salt.state       :1951][INFO    ][13879] Completed state [/etc/salt/minion.d/minion.conf] at time 10:01:23.850207 duration_in_ms=283.992
2019-05-10 10:01:23,850 [salt.state       :1780][INFO    ][13879] Running state [python-netaddr] at time 10:01:23.850496
2019-05-10 10:01:23,850 [salt.state       :1813][INFO    ][13879] Executing state pkg.installed for [python-netaddr]
2019-05-10 10:01:23,860 [salt.state       :300 ][INFO    ][13879] All specified packages are already installed
2019-05-10 10:01:23,860 [salt.state       :1951][INFO    ][13879] Completed state [python-netaddr] at time 10:01:23.860578 duration_in_ms=10.082
2019-05-10 10:01:23,863 [salt.state       :1780][INFO    ][13879] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 10:01:23.863760
2019-05-10 10:01:23,864 [salt.state       :1813][INFO    ][13879] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-10 10:01:23,878 [salt.state       :300 ][INFO    ][13879] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-10 10:01:23,878 [salt.state       :1951][INFO    ][13879] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 10:01:23.878894 duration_in_ms=15.134
2019-05-10 10:01:23,880 [salt.state       :1780][INFO    ][13879] Running state [salt-minion] at time 10:01:23.880179
2019-05-10 10:01:23,880 [salt.state       :1813][INFO    ][13879] Executing state service.running for [salt-minion]
2019-05-10 10:01:23,881 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-10 10:01:23,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-10 10:01:23,953 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-10 10:01:23,980 [salt.state       :300 ][INFO    ][13879] The service salt-minion is already running
2019-05-10 10:01:23,980 [salt.state       :1951][INFO    ][13879] Completed state [salt-minion] at time 10:01:23.980740 duration_in_ms=100.561
2019-05-10 10:01:23,983 [salt.state       :1780][INFO    ][13879] Running state [/etc/salt/grains.d] at time 10:01:23.983289
2019-05-10 10:01:23,983 [salt.state       :1813][INFO    ][13879] Executing state file.directory for [/etc/salt/grains.d]
2019-05-10 10:01:23,985 [salt.state       :300 ][INFO    ][13879] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-10 10:01:23,985 [salt.state       :1951][INFO    ][13879] Completed state [/etc/salt/grains.d] at time 10:01:23.985579 duration_in_ms=2.29
2019-05-10 10:01:23,986 [salt.state       :1780][INFO    ][13879] Running state [/etc/salt/grains] at time 10:01:23.986523
2019-05-10 10:01:23,986 [salt.state       :1813][INFO    ][13879] Executing state file.managed for [/etc/salt/grains]
2019-05-10 10:01:23,987 [salt.state       :300 ][INFO    ][13879] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-10 10:01:23,988 [salt.state       :1951][INFO    ][13879] Completed state [/etc/salt/grains] at time 10:01:23.988006 duration_in_ms=1.483
2019-05-10 10:01:23,988 [salt.state       :1780][INFO    ][13879] Running state [/etc/salt/grains.d/placeholder] at time 10:01:23.988669
2019-05-10 10:01:23,989 [salt.state       :1813][INFO    ][13879] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-10 10:01:23,991 [salt.state       :300 ][INFO    ][13879] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-10 10:01:23,991 [salt.state       :1951][INFO    ][13879] Completed state [/etc/salt/grains.d/placeholder] at time 10:01:23.991769 duration_in_ms=3.1
2019-05-10 10:01:23,992 [salt.state       :1780][INFO    ][13879] Running state [/etc/salt/grains.d/sphinx] at time 10:01:23.992281
2019-05-10 10:01:23,992 [salt.state       :1813][INFO    ][13879] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-10 10:01:23,994 [salt.state       :300 ][INFO    ][13879] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-10 10:01:23,995 [salt.state       :1951][INFO    ][13879] Completed state [/etc/salt/grains.d/sphinx] at time 10:01:23.995016 duration_in_ms=2.736
2019-05-10 10:01:23,997 [salt.state       :1780][INFO    ][13879] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 10:01:23.997206
2019-05-10 10:01:23,997 [salt.state       :1813][INFO    ][13879] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-10 10:01:23,997 [salt.state       :300 ][INFO    ][13879] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-10 10:01:23,998 [salt.state       :1951][INFO    ][13879] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 10:01:23.998069 duration_in_ms=0.863
2019-05-10 10:01:23,998 [salt.state       :1780][INFO    ][13879] Running state [/etc/salt/grains.d/dns_records] at time 10:01:23.998584
2019-05-10 10:01:23,998 [salt.state       :1813][INFO    ][13879] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-10 10:01:23,999 [salt.state       :300 ][INFO    ][13879] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-10 10:01:24,000 [salt.state       :1951][INFO    ][13879] Completed state [/etc/salt/grains.d/dns_records] at time 10:01:24.000161 duration_in_ms=1.577
2019-05-10 10:01:24,001 [salt.state       :1780][INFO    ][13879] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 10:01:24.001099
2019-05-10 10:01:24,001 [salt.state       :1813][INFO    ][13879] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-10 10:01:24,003 [salt.state       :300 ][INFO    ][13879] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-10 10:01:24,003 [salt.state       :1951][INFO    ][13879] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 10:01:24.003429 duration_in_ms=2.329
2019-05-10 10:01:24,004 [salt.state       :1780][INFO    ][13879] Running state [/etc/salt/grains.d/salt] at time 10:01:24.004237
2019-05-10 10:01:24,004 [salt.state       :1813][INFO    ][13879] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-10 10:01:24,006 [salt.state       :300 ][INFO    ][13879] File /etc/salt/grains.d/salt is in the correct state
2019-05-10 10:01:24,006 [salt.state       :1951][INFO    ][13879] Completed state [/etc/salt/grains.d/salt] at time 10:01:24.006878 duration_in_ms=2.642
2019-05-10 10:01:24,008 [salt.state       :1780][INFO    ][13879] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 10:01:24.008312
2019-05-10 10:01:24,008 [salt.state       :1813][INFO    ][13879] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-10 10:01:24,009 [salt.state       :300 ][INFO    ][13879] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-10 10:01:24,009 [salt.state       :1951][INFO    ][13879] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 10:01:24.009497 duration_in_ms=1.185
2019-05-10 10:01:24,012 [salt.state       :1780][INFO    ][13879] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 10:01:24.012679
2019-05-10 10:01:24,013 [salt.state       :1813][INFO    ][13879] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-10 10:01:24,013 [salt.state       :300 ][INFO    ][13879] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-10 10:01:24,014 [salt.state       :1951][INFO    ][13879] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 10:01:24.014843 duration_in_ms=2.165
2019-05-10 10:01:24,015 [salt.state       :1780][INFO    ][13879] Running state [mine.update] at time 10:01:24.015876
2019-05-10 10:01:24,016 [salt.state       :1813][INFO    ][13879] Executing state module.wait for [mine.update]
2019-05-10 10:01:24,016 [salt.state       :300 ][INFO    ][13879] No changes made for mine.update
2019-05-10 10:01:24,017 [salt.state       :1951][INFO    ][13879] Completed state [mine.update] at time 10:01:24.017010 duration_in_ms=1.134
2019-05-10 10:01:24,017 [salt.state       :1780][INFO    ][13879] Running state [ca-certificates] at time 10:01:24.017392
2019-05-10 10:01:24,018 [salt.state       :1813][INFO    ][13879] Executing state pkg.installed for [ca-certificates]
2019-05-10 10:01:24,028 [salt.state       :300 ][INFO    ][13879] All specified packages are already installed
2019-05-10 10:01:24,029 [salt.state       :1951][INFO    ][13879] Completed state [ca-certificates] at time 10:01:24.029118 duration_in_ms=11.727
2019-05-10 10:01:24,029 [salt.state       :1780][INFO    ][13879] Running state [update-ca-certificates] at time 10:01:24.029835
2019-05-10 10:01:24,030 [salt.state       :1813][INFO    ][13879] Executing state cmd.wait for [update-ca-certificates]
2019-05-10 10:01:24,030 [salt.state       :300 ][INFO    ][13879] No changes made for update-ca-certificates
2019-05-10 10:01:24,030 [salt.state       :1951][INFO    ][13879] Completed state [update-ca-certificates] at time 10:01:24.030622 duration_in_ms=0.786
2019-05-10 10:01:24,030 [salt.state       :1780][INFO    ][13879] Running state [iptables] at time 10:01:24.030876
2019-05-10 10:01:24,031 [salt.state       :1813][INFO    ][13879] Executing state pkg.installed for [iptables]
2019-05-10 10:01:24,041 [salt.state       :300 ][INFO    ][13879] All specified packages are already installed
2019-05-10 10:01:24,042 [salt.state       :1951][INFO    ][13879] Completed state [iptables] at time 10:01:24.042278 duration_in_ms=11.402
2019-05-10 10:01:24,042 [salt.state       :1780][INFO    ][13879] Running state [iptables-persistent] at time 10:01:24.042543
2019-05-10 10:01:24,042 [salt.state       :1813][INFO    ][13879] Executing state pkg.installed for [iptables-persistent]
2019-05-10 10:01:24,052 [salt.state       :300 ][INFO    ][13879] All specified packages are already installed
2019-05-10 10:01:24,052 [salt.state       :1951][INFO    ][13879] Completed state [iptables-persistent] at time 10:01:24.052724 duration_in_ms=10.181
2019-05-10 10:01:24,053 [salt.state       :1780][INFO    ][13879] Running state [iptables_modules_v4_load] at time 10:01:24.053744
2019-05-10 10:01:24,054 [salt.state       :1813][INFO    ][13879] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-10 10:01:24,054 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command 'lsmod' in directory '/root'
2019-05-10 10:01:24,078 [salt.state       :300 ][INFO    ][13879] Kernel modules iptable_filter, ip_tables are already present
2019-05-10 10:01:24,079 [salt.state       :1951][INFO    ][13879] Completed state [iptables_modules_v4_load] at time 10:01:24.079488 duration_in_ms=25.743
2019-05-10 10:01:24,080 [salt.state       :1780][INFO    ][13879] Running state [/etc/iptables/rules.v4] at time 10:01:24.080779
2019-05-10 10:01:24,081 [salt.state       :1813][INFO    ][13879] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-10 10:01:24,199 [salt.state       :300 ][INFO    ][13879] File /etc/iptables/rules.v4 is in the correct state
2019-05-10 10:01:24,199 [salt.state       :1951][INFO    ][13879] Completed state [/etc/iptables/rules.v4] at time 10:01:24.199248 duration_in_ms=118.47
2019-05-10 10:01:24,200 [salt.state       :1780][INFO    ][13879] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 10:01:24.200343
2019-05-10 10:01:24,200 [salt.state       :1813][INFO    ][13879] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-10 10:01:24,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-10 10:01:24,220 [salt.state       :300 ][INFO    ][13879] onlyif execution failed
2019-05-10 10:01:24,221 [salt.state       :1951][INFO    ][13879] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 10:01:24.221054 duration_in_ms=20.71
2019-05-10 10:01:24,223 [salt.state       :1780][INFO    ][13879] Running state [netfilter-persistent] at time 10:01:24.223781
2019-05-10 10:01:24,224 [salt.state       :1813][INFO    ][13879] Executing state service.running for [netfilter-persistent]
2019-05-10 10:01:24,225 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-10 10:01:24,250 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-10 10:01:24,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-10 10:01:24,299 [salt.state       :300 ][INFO    ][13879] The service netfilter-persistent is already running
2019-05-10 10:01:24,300 [salt.state       :1951][INFO    ][13879] Completed state [netfilter-persistent] at time 10:01:24.300526 duration_in_ms=76.745
2019-05-10 10:01:24,304 [salt.state       :1780][INFO    ][13879] Running state [iptables_extra.remove_stale_tables] at time 10:01:24.304085
2019-05-10 10:01:24,304 [salt.state       :1813][INFO    ][13879] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-10 10:01:24,305 [salt.state       :300 ][INFO    ][13879] No changes made for iptables_extra.remove_stale_tables
2019-05-10 10:01:24,305 [salt.state       :1951][INFO    ][13879] Completed state [iptables_extra.remove_stale_tables] at time 10:01:24.305523 duration_in_ms=1.437
2019-05-10 10:01:24,306 [salt.state       :1780][INFO    ][13879] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 10:01:24.306544
2019-05-10 10:01:24,307 [salt.state       :1813][INFO    ][13879] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-10 10:01:24,308 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13879] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-10 10:01:24,324 [salt.state       :300 ][INFO    ][13879] onlyif execution failed
2019-05-10 10:01:24,324 [salt.state       :1951][INFO    ][13879] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 10:01:24.324327 duration_in_ms=17.783
2019-05-10 10:01:24,325 [salt.state       :1780][INFO    ][13879] Running state [/etc/iptables/rules.v6] at time 10:01:24.325379
2019-05-10 10:01:24,328 [salt.state       :1813][INFO    ][13879] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-10 10:01:24,328 [salt.state       :300 ][INFO    ][13879] File /etc/iptables/rules.v6 is not present
2019-05-10 10:01:24,329 [salt.state       :1951][INFO    ][13879] Completed state [/etc/iptables/rules.v6] at time 10:01:24.329301 duration_in_ms=3.921
2019-05-10 10:01:24,330 [salt.state       :1780][INFO    ][13879] Running state [iptables_extra.flush_all] at time 10:01:24.330442
2019-05-10 10:01:24,330 [salt.state       :1813][INFO    ][13879] Executing state module.wait for [iptables_extra.flush_all]
2019-05-10 10:01:24,331 [salt.state       :300 ][INFO    ][13879] No changes made for iptables_extra.flush_all
2019-05-10 10:01:24,331 [salt.state       :1951][INFO    ][13879] Completed state [iptables_extra.flush_all] at time 10:01:24.331737 duration_in_ms=1.295
2019-05-10 10:01:24,336 [salt.minion      :1711][INFO    ][13879] Returning information for job: 20190510100112718446
2019-05-10 10:01:25,031 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510100125018098
2019-05-10 10:01:25,057 [salt.minion      :1432][INFO    ][13968] Starting a new job with PID 13968
2019-05-10 10:01:26,020 [salt.state       :915 ][INFO    ][13968] Loading fresh modules for state activity
2019-05-10 10:01:27,236 [salt.state       :1780][INFO    ][13968] Running state [maas-rack-controller] at time 10:01:27.236219
2019-05-10 10:01:27,236 [salt.state       :1813][INFO    ][13968] Executing state pkg.installed for [maas-rack-controller]
2019-05-10 10:01:27,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13968] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-10 10:01:27,360 [salt.state       :300 ][INFO    ][13968] All specified packages are already installed
2019-05-10 10:01:27,360 [salt.state       :1951][INFO    ][13968] Completed state [maas-rack-controller] at time 10:01:27.360445 duration_in_ms=124.227
2019-05-10 10:01:27,360 [salt.state       :1780][INFO    ][13968] Running state [ipmitool] at time 10:01:27.360812
2019-05-10 10:01:27,361 [salt.state       :1813][INFO    ][13968] Executing state pkg.installed for [ipmitool]
2019-05-10 10:01:27,370 [salt.state       :300 ][INFO    ][13968] All specified packages are already installed
2019-05-10 10:01:27,371 [salt.state       :1951][INFO    ][13968] Completed state [ipmitool] at time 10:01:27.371078 duration_in_ms=10.266
2019-05-10 10:01:27,374 [salt.state       :1780][INFO    ][13968] Running state [/etc/maas/rackd.conf] at time 10:01:27.374818
2019-05-10 10:01:27,375 [salt.state       :1813][INFO    ][13968] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-10 10:01:27,376 [salt.state       :300 ][INFO    ][13968] No changes needed to be made
2019-05-10 10:01:27,376 [salt.state       :1951][INFO    ][13968] Completed state [/etc/maas/rackd.conf] at time 10:01:27.376266 duration_in_ms=1.449
2019-05-10 10:01:27,376 [salt.state       :1780][INFO    ][13968] Running state [/etc/maas/rackd.conf] at time 10:01:27.376538
2019-05-10 10:01:27,376 [salt.state       :1813][INFO    ][13968] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-10 10:01:27,377 [salt.loaded.int.states.file:2298][WARNING ][13968] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-10 10:01:27,377 [salt.state       :300 ][INFO    ][13968] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-10 10:01:27,378 [salt.state       :1951][INFO    ][13968] Completed state [/etc/maas/rackd.conf] at time 10:01:27.378169 duration_in_ms=1.63
2019-05-10 10:01:27,379 [salt.state       :1780][INFO    ][13968] Running state [maas-rackd] at time 10:01:27.379217
2019-05-10 10:01:27,379 [salt.state       :1813][INFO    ][13968] Executing state service.running for [maas-rackd]
2019-05-10 10:01:27,380 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13968] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-10 10:01:27,425 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13968] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-10 10:01:27,448 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13968] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-10 10:01:27,467 [salt.state       :300 ][INFO    ][13968] The service maas-rackd is already running
2019-05-10 10:01:27,468 [salt.state       :1951][INFO    ][13968] Completed state [maas-rackd] at time 10:01:27.468063 duration_in_ms=88.845
2019-05-10 10:01:27,471 [salt.minion      :1711][INFO    ][13968] Returning information for job: 20190510100125018098
2019-05-10 10:01:28,152 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510100128139414
2019-05-10 10:01:28,177 [salt.minion      :1432][INFO    ][13991] Starting a new job with PID 13991
2019-05-10 10:01:29,133 [salt.state       :915 ][INFO    ][13991] Loading fresh modules for state activity
2019-05-10 10:01:30,560 [salt.state       :1780][INFO    ][13991] Running state [maas-region-controller] at time 10:01:30.560283
2019-05-10 10:01:30,560 [salt.state       :1813][INFO    ][13991] Executing state pkg.installed for [maas-region-controller]
2019-05-10 10:01:30,561 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-10 10:01:30,701 [salt.state       :300 ][INFO    ][13991] All specified packages are already installed
2019-05-10 10:01:30,701 [salt.state       :1951][INFO    ][13991] Completed state [maas-region-controller] at time 10:01:30.701632 duration_in_ms=141.35
2019-05-10 10:01:30,702 [salt.state       :1780][INFO    ][13991] Running state [python-oauth] at time 10:01:30.702009
2019-05-10 10:01:30,702 [salt.state       :1813][INFO    ][13991] Executing state pkg.installed for [python-oauth]
2019-05-10 10:01:30,712 [salt.state       :300 ][INFO    ][13991] All specified packages are already installed
2019-05-10 10:01:30,712 [salt.state       :1951][INFO    ][13991] Completed state [python-oauth] at time 10:01:30.712404 duration_in_ms=10.395
2019-05-10 10:01:30,716 [salt.state       :1780][INFO    ][13991] Running state [/etc/maas/regiond.conf] at time 10:01:30.716047
2019-05-10 10:01:30,716 [salt.state       :1813][INFO    ][13991] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-10 10:01:30,720 [salt.state       :300 ][INFO    ][13991] No changes needed to be made
2019-05-10 10:01:30,721 [salt.state       :1951][INFO    ][13991] Completed state [/etc/maas/regiond.conf] at time 10:01:30.721182 duration_in_ms=5.135
2019-05-10 10:01:30,721 [salt.state       :1780][INFO    ][13991] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 10:01:30.721686
2019-05-10 10:01:30,722 [salt.state       :1813][INFO    ][13991] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-10 10:01:30,794 [salt.state       :300 ][INFO    ][13991] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-10 10:01:30,794 [salt.state       :1951][INFO    ][13991] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 10:01:30.794462 duration_in_ms=72.775
2019-05-10 10:01:30,795 [salt.state       :1780][INFO    ][13991] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:01:30.794977
2019-05-10 10:01:30,795 [salt.state       :1813][INFO    ][13991] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-10 10:01:30,799 [salt.state       :300 ][INFO    ][13991] No changes needed to be made
2019-05-10 10:01:30,800 [salt.state       :1951][INFO    ][13991] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:01:30.800024 duration_in_ms=5.046
2019-05-10 10:01:30,800 [salt.state       :1780][INFO    ][13991] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:01:30.800549
2019-05-10 10:01:30,800 [salt.state       :1813][INFO    ][13991] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-10 10:01:30,805 [salt.state       :300 ][INFO    ][13991] No changes needed to be made
2019-05-10 10:01:30,806 [salt.state       :1951][INFO    ][13991] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:01:30.806517 duration_in_ms=5.968
2019-05-10 10:01:30,807 [salt.state       :1780][INFO    ][13991] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 10:01:30.807028
2019-05-10 10:01:30,807 [salt.state       :1813][INFO    ][13991] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-10 10:01:30,834 [salt.state       :300 ][INFO    ][13991] No changes needed to be made
2019-05-10 10:01:30,834 [salt.state       :1951][INFO    ][13991] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 10:01:30.834453 duration_in_ms=27.424
2019-05-10 10:01:30,835 [salt.state       :1780][INFO    ][13991] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 10:01:30.834965
2019-05-10 10:01:30,835 [salt.state       :1813][INFO    ][13991] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-10 10:01:30,849 [salt.state       :300 ][INFO    ][13991] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-10 10:01:30,850 [salt.state       :1951][INFO    ][13991] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 10:01:30.850222 duration_in_ms=15.257
2019-05-10 10:01:30,851 [salt.state       :1780][INFO    ][13991] Running state [a2enmod headers] at time 10:01:30.851594
2019-05-10 10:01:30,851 [salt.state       :1813][INFO    ][13991] Executing state cmd.run for [a2enmod headers]
2019-05-10 10:01:30,852 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command 'a2enmod headers' in directory '/root'
2019-05-10 10:01:30,930 [salt.state       :300 ][INFO    ][13991] {'pid': 14038, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-10 10:01:30,931 [salt.state       :1951][INFO    ][13991] Completed state [a2enmod headers] at time 10:01:30.930851 duration_in_ms=79.256
2019-05-10 10:01:30,931 [salt.state       :1780][INFO    ][13991] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 10:01:30.931592
2019-05-10 10:01:30,932 [salt.state       :1813][INFO    ][13991] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-10 10:01:30,955 [salt.state       :300 ][INFO    ][13991] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-10 10:01:30,955 [salt.state       :1951][INFO    ][13991] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 10:01:30.955642 duration_in_ms=24.05
2019-05-10 10:01:30,956 [salt.state       :1780][INFO    ][13991] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 10:01:30.956351
2019-05-10 10:01:30,956 [salt.state       :1813][INFO    ][13991] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-10 10:01:31,016 [salt.state       :300 ][INFO    ][13991] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-10 10:01:31,016 [salt.state       :1951][INFO    ][13991] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 10:01:31.016640 duration_in_ms=60.288
2019-05-10 10:01:31,018 [salt.state       :1780][INFO    ][13991] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 10:01:31.018359
2019-05-10 10:01:31,018 [salt.state       :1813][INFO    ][13991] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-10 10:01:31,083 [salt.state       :300 ][INFO    ][13991] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-10 10:01:31,084 [salt.state       :1951][INFO    ][13991] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 10:01:31.084107 duration_in_ms=66.026
2019-05-10 10:01:31,084 [salt.state       :1780][INFO    ][13991] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 10:01:31.084627
2019-05-10 10:01:31,085 [salt.state       :1813][INFO    ][13991] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-10 10:01:31,160 [salt.state       :300 ][INFO    ][13991] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-10 10:01:31,160 [salt.state       :1951][INFO    ][13991] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 10:01:31.160277 duration_in_ms=75.65
2019-05-10 10:01:31,160 [salt.state       :1780][INFO    ][13991] Running state [/root/.pgpass] at time 10:01:31.160581
2019-05-10 10:01:31,160 [salt.state       :1813][INFO    ][13991] Executing state file.managed for [/root/.pgpass]
2019-05-10 10:01:31,219 [salt.state       :300 ][INFO    ][13991] File /root/.pgpass is in the correct state
2019-05-10 10:01:31,220 [salt.state       :1951][INFO    ][13991] Completed state [/root/.pgpass] at time 10:01:31.220061 duration_in_ms=59.48
2019-05-10 10:01:31,225 [salt.state       :1780][INFO    ][13991] Running state [maas-region syncdb --noinput] at time 10:01:31.225557
2019-05-10 10:01:31,226 [salt.state       :1813][INFO    ][13991] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-10 10:01:31,227 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-10 10:01:33,924 [salt.state       :300 ][INFO    ][13991] {'pid': 14052, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sessions, contenttypes, piston3, maasserver, auth, sites, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-10 10:01:33,925 [salt.state       :1951][INFO    ][13991] Completed state [maas-region syncdb --noinput] at time 10:01:33.925122 duration_in_ms=2699.563
2019-05-10 10:01:33,927 [salt.state       :2022][WARNING ][13991] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-10 10:01:33,931 [salt.state       :1780][INFO    ][13991] Running state [maas-regiond] at time 10:01:33.930916
2019-05-10 10:01:33,931 [salt.state       :1813][INFO    ][13991] Executing state service.running for [maas-regiond]
2019-05-10 10:01:33,933 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-10 10:01:33,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-10 10:01:33,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-10 10:01:34,006 [salt.state       :300 ][INFO    ][13991] The service maas-regiond is already running
2019-05-10 10:01:34,007 [salt.state       :1951][INFO    ][13991] Completed state [maas-regiond] at time 10:01:34.007255 duration_in_ms=76.339
2019-05-10 10:01:34,012 [salt.state       :1780][INFO    ][13991] Running state [bind9] at time 10:01:34.011916
2019-05-10 10:01:34,012 [salt.state       :1813][INFO    ][13991] Executing state service.running for [bind9]
2019-05-10 10:01:34,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-10 10:01:34,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-10 10:01:34,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-10 10:01:34,069 [salt.state       :300 ][INFO    ][13991] The service bind9 is already running
2019-05-10 10:01:34,072 [salt.state       :1951][INFO    ][13991] Completed state [bind9] at time 10:01:34.072152 duration_in_ms=60.236
2019-05-10 10:01:34,075 [salt.state       :1780][INFO    ][13991] Running state [apache2] at time 10:01:34.075314
2019-05-10 10:01:34,075 [salt.state       :1813][INFO    ][13991] Executing state service.running for [apache2]
2019-05-10 10:01:34,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-10 10:01:34,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-10 10:01:34,116 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-10 10:01:34,145 [salt.state       :300 ][INFO    ][13991] The service apache2 is already running
2019-05-10 10:01:34,145 [salt.state       :1951][INFO    ][13991] Completed state [apache2] at time 10:01:34.145819 duration_in_ms=70.505
2019-05-10 10:01:34,147 [salt.state       :1780][INFO    ][13991] Running state [maasng.wait_for_http_code] at time 10:01:34.147157
2019-05-10 10:01:34,147 [salt.state       :1813][INFO    ][13991] Executing state module.run for [maasng.wait_for_http_code]
2019-05-10 10:01:34,147 [salt.utils.decorators:613 ][WARNING ][13991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 10:01:34,266 [salt.state       :300 ][INFO    ][13991] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-10 10:01:34,266 [salt.state       :1951][INFO    ][13991] Completed state [maasng.wait_for_http_code] at time 10:01:34.266474 duration_in_ms=119.317
2019-05-10 10:01:34,267 [salt.state       :1780][INFO    ][13991] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 10:01:34.267359
2019-05-10 10:01:34,267 [salt.state       :1813][INFO    ][13991] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-10 10:01:34,268 [salt.state       :300 ][INFO    ][13991] /var/lib/maas/.setup_admin exists
2019-05-10 10:01:34,268 [salt.state       :1951][INFO    ][13991] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 10:01:34.268417 duration_in_ms=1.058
2019-05-10 10:01:34,269 [salt.state       :1780][INFO    ][13991] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:01:34.269133
2019-05-10 10:01:34,269 [salt.state       :1813][INFO    ][13991] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 10:01:34,270 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13991] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 10:01:36,054 [salt.state       :300 ][INFO    ][13991] {'pid': 14076, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 10:01:36,055 [salt.state       :1951][INFO    ][13991] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:01:36.055470 duration_in_ms=1786.335
2019-05-10 10:01:36,068 [salt.state       :1780][INFO    ][13991] Running state [maas_region_boot_source_resources_mirror] at time 10:01:36.068272
2019-05-10 10:01:36,068 [salt.state       :1813][INFO    ][13991] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-10 10:01:36,163 [salt.state       :300 ][INFO    ][13991] {'changes': {}}
2019-05-10 10:01:36,164 [salt.state       :1951][INFO    ][13991] Completed state [maas_region_boot_source_resources_mirror] at time 10:01:36.163976 duration_in_ms=95.704
2019-05-10 10:01:36,165 [salt.state       :1780][INFO    ][13991] Running state [maasng.boot_resources_import] at time 10:01:36.165264
2019-05-10 10:01:36,166 [salt.state       :1813][INFO    ][13991] Executing state module.run for [maasng.boot_resources_import]
2019-05-10 10:01:36,167 [salt.utils.decorators:613 ][WARNING ][13991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 10:01:36,260 [salt.loaded.ext.module.maasng:1600][INFO    ][13991] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-10 10:01:41,311 [salt.loaded.ext.module.maasng:1600][INFO    ][13991] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-10 10:01:43,193 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100143182517
2019-05-10 10:01:43,220 [salt.minion      :1432][INFO    ][14105] Starting a new job with PID 14105
2019-05-10 10:01:43,252 [salt.minion      :1711][INFO    ][14105] Returning information for job: 20190510100143182517
2019-05-10 10:01:46,414 [salt.state       :300 ][INFO    ][13991] {'ret': True}
2019-05-10 10:01:46,414 [salt.state       :1951][INFO    ][13991] Completed state [maasng.boot_resources_import] at time 10:01:46.414637 duration_in_ms=10249.372
2019-05-10 10:01:46,416 [salt.state       :1780][INFO    ][13991] Running state [maas_region_boot_sources_selection_xenial] at time 10:01:46.416028
2019-05-10 10:01:46,416 [salt.state       :1813][INFO    ][13991] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-10 10:01:46,601 [salt.state       :300 ][INFO    ][13991] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-10 10:01:46,602 [salt.state       :1951][INFO    ][13991] Completed state [maas_region_boot_sources_selection_xenial] at time 10:01:46.602114 duration_in_ms=186.085
2019-05-10 10:01:46,603 [salt.state       :1780][INFO    ][13991] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 10:01:46.603182
2019-05-10 10:01:46,603 [salt.state       :1813][INFO    ][13991] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-10 10:01:46,604 [salt.utils.decorators:613 ][WARNING ][13991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 10:01:46,604 [salt.loaded.ext.module.maasng:1771][INFO    ][13991] boot-sources sync initiated for ALL Rack's
2019-05-10 10:01:47,651 [salt.state       :300 ][INFO    ][13991] {'ret': True}
2019-05-10 10:01:47,652 [salt.state       :1951][INFO    ][13991] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 10:01:47.652098 duration_in_ms=1048.915
2019-05-10 10:01:47,654 [salt.state       :1780][INFO    ][13991] Running state [maas.process_maas_config] at time 10:01:47.654881
2019-05-10 10:01:47,655 [salt.state       :1813][INFO    ][13991] Executing state module.run for [maas.process_maas_config]
2019-05-10 10:01:47,656 [salt.utils.decorators:613 ][WARNING ][13991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 10:01:47,656 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=enable_http_proxy value=True
2019-05-10 10:01:47,710 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=upstream_dns value=8.8.8.8
2019-05-10 10:01:47,766 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=commissioning_distro_series value=xenial
2019-05-10 10:01:49,436 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=default_osystem value=ubuntu
2019-05-10 10:01:49,491 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=active_discovery_interval value=600
2019-05-10 10:01:49,552 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=dnssec_validation value=no
2019-05-10 10:01:49,604 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=maas_name value=mas01
2019-05-10 10:01:49,657 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=network_discovery value=enabled
2019-05-10 10:01:49,764 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=enable_third_party_drivers value=True
2019-05-10 10:01:49,813 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=default_storage_layout value=lvm
2019-05-10 10:01:49,880 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=ntp_external_only value=True
2019-05-10 10:01:49,931 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-10 10:01:49,978 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=default_distro_series value=xenial
2019-05-10 10:01:50,045 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-10 10:01:50,160 [salt.state       :300 ][INFO    ][13991] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-10 10:01:50,161 [salt.state       :1951][INFO    ][13991] Completed state [maas.process_maas_config] at time 10:01:50.161207 duration_in_ms=2506.327
2019-05-10 10:01:50,162 [salt.state       :1780][INFO    ][13991] Running state [pxe_admin] at time 10:01:50.162815
2019-05-10 10:01:50,163 [salt.state       :1813][INFO    ][13991] Executing state maasng.fabric_present for [pxe_admin]
2019-05-10 10:01:50,218 [salt.loaded.ext.module.maasng:945 ][INFO    ][13991] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, 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'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'fabric': u'fabric-1'}], 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'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'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-10 10:01:50,298 [salt.loaded.ext.module.maasng:1008][WARNING ][13991] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-10 10:01:50,299 [salt.loaded.ext.module.maasng:1011][WARNING ][13991] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-10 10:01:50,363 [salt.state       :300 ][INFO    ][13991] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-10 10:01:50,364 [salt.state       :1951][INFO    ][13991] Completed state [pxe_admin] at time 10:01:50.363984 duration_in_ms=201.168
2019-05-10 10:01:50,364 [salt.state       :1780][INFO    ][13991] Running state [vlan 0] at time 10:01:50.364339
2019-05-10 10:01:50,364 [salt.state       :1813][INFO    ][13991] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-10 10:01:50,423 [salt.loaded.ext.module.maasng:945 ][INFO    ][13991] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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'class_type': None}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'class_type': None}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', 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'class_type': u''}]
2019-05-10 10:01:50,538 [salt.loaded.ext.module.maasng:945 ][INFO    ][13991] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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'class_type': None}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'class_type': None}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', 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'class_type': u''}]
2019-05-10 10:01:50,787 [salt.loaded.ext.module.maasng:945 ][INFO    ][13991] [{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': 0}, {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': 1}, {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'a8pcas', 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/', u'id': 2}]
2019-05-10 10:01:50,871 [salt.state       :300 ][INFO    ][13991] {'new': 'Vlan untagged was updated'}
2019-05-10 10:01:50,871 [salt.state       :1951][INFO    ][13991] Completed state [vlan 0] at time 10:01:50.871833 duration_in_ms=507.492
2019-05-10 10:01:50,873 [salt.state       :1780][INFO    ][13991] Running state [192.168.11.0/24] at time 10:01:50.873367
2019-05-10 10:01:50,873 [salt.state       :1813][INFO    ][13991] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-10 10:01:51,088 [salt.loaded.ext.module.maasng:945 ][INFO    ][13991] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, 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'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'fabric': u'fabric-1'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-10 10:01:51,089 [salt.loaded.ext.module.maasng:1235][WARNING ][13991] Ignoring parameter vlan:0
2019-05-10 10:01:51,149 [salt.state       :300 ][INFO    ][13991] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-10 10:01:51,149 [salt.state       :1951][INFO    ][13991] Completed state [192.168.11.0/24] at time 10:01:51.149374 duration_in_ms=276.006
2019-05-10 10:01:51,150 [salt.state       :1780][INFO    ][13991] Running state [maas_create_iprange_1] at time 10:01:51.150441
2019-05-10 10:01:51,150 [salt.state       :1813][INFO    ][13991] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-10 10:01:51,214 [salt.state       :300 ][INFO    ][13991] Iprange maas_create_iprange_1 already exist.
2019-05-10 10:01:51,215 [salt.state       :1951][INFO    ][13991] Completed state [maas_create_iprange_1] at time 10:01:51.215115 duration_in_ms=64.673
2019-05-10 10:01:51,215 [salt.state       :1780][INFO    ][13991] Running state [vlan 0] at time 10:01:51.215509
2019-05-10 10:01:51,215 [salt.state       :1813][INFO    ][13991] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-10 10:01:51,267 [salt.loaded.ext.module.maasng:945 ][INFO    ][13991] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'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'class_type': None}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'class_type': None}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', 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'class_type': u''}]
2019-05-10 10:01:51,355 [salt.loaded.ext.module.maasng:945 ][INFO    ][13991] [{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': 0}, {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': 1}, {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'a8pcas', 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/', u'id': 2}]
2019-05-10 10:01:51,623 [salt.loaded.ext.module.maasng:945 ][INFO    ][13991] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, 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'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'fabric': u'fabric-1'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8pcas', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-10 10:01:51,749 [salt.state       :300 ][INFO    ][13991] {'new': 'Vlan untagged was updated'}
2019-05-10 10:01:51,749 [salt.state       :1951][INFO    ][13991] Completed state [vlan 0] at time 10:01:51.749545 duration_in_ms=534.036
2019-05-10 10:01:51,750 [salt.state       :1780][INFO    ][13991] Running state [opnfv] at time 10:01:51.750863
2019-05-10 10:01:51,751 [salt.state       :1813][INFO    ][13991] Executing state maasng.sshkey_present for [opnfv]
2019-05-10 10:01:51,799 [salt.loaded.ext.module.maasng:1903][INFO    ][13991] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-05-10 10:01:51,799 [salt.state       :300 ][INFO    ][13991] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-10 10:01:51,800 [salt.state       :1951][INFO    ][13991] Completed state [opnfv] at time 10:01:51.799958 duration_in_ms=49.095
2019-05-10 10:01:51,800 [salt.state       :1780][INFO    ][13991] Running state [maas.process_tags] at time 10:01:51.800625
2019-05-10 10:01:51,801 [salt.state       :1813][INFO    ][13991] Executing state module.run for [maas.process_tags]
2019-05-10 10:01:51,801 [salt.utils.decorators:613 ][WARNING ][13991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 10:01:51,846 [salt.loaded.ext.module.maas:92  ][INFO    ][13991] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-10 10:01:51,904 [salt.state       :300 ][INFO    ][13991] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-10 10:01:51,905 [salt.state       :1951][INFO    ][13991] Completed state [maas.process_tags] at time 10:01:51.905095 duration_in_ms=104.47
2019-05-10 10:01:51,908 [salt.minion      :1711][INFO    ][13991] Returning information for job: 20190510100128139414
2019-05-10 10:01:52,632 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510100152624212
2019-05-10 10:01:52,654 [salt.minion      :1432][INFO    ][14466] Starting a new job with PID 14466
2019-05-10 10:01:58,756 [salt.state       :915 ][INFO    ][14466] Loading fresh modules for state activity
2019-05-10 10:01:58,861 [salt.state       :1780][INFO    ][14466] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:01:58.861385
2019-05-10 10:01:58,862 [salt.state       :1813][INFO    ][14466] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 10:01:58,866 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14466] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 10:02:00,808 [salt.state       :300 ][INFO    ][14466] {'pid': 14489, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 10:02:00,809 [salt.state       :1951][INFO    ][14466] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:02:00.809496 duration_in_ms=1948.11
2019-05-10 10:02:00,814 [salt.state       :1780][INFO    ][14466] Running state [maas.process_machines] at time 10:02:00.813589
2019-05-10 10:02:00,814 [salt.state       :1813][INFO    ][14466] Executing state module.run for [maas.process_machines]
2019-05-10 10:02:00,815 [salt.utils.decorators:613 ][WARNING ][14466] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 10:02:01,409 [salt.loaded.ext.module.maas:412 ][WARNING ][14466] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 10:02:01,411 [salt.loaded.ext.module.maas:92  ][INFO    ][14466] 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=kdx7ph architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-10 10:02:02,670 [salt.loaded.ext.module.maas:412 ][WARNING ][14466] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 10:02:02,671 [salt.loaded.ext.module.maas:92  ][INFO    ][14466] 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=mcdgqe architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-10 10:02:03,906 [salt.loaded.ext.module.maas:412 ][WARNING ][14466] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 10:02:03,907 [salt.loaded.ext.module.maas:92  ][INFO    ][14466] 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=acnhww architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-10 10:02:05,155 [salt.loaded.ext.module.maas:412 ][WARNING ][14466] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 10:02:05,156 [salt.loaded.ext.module.maas:92  ][INFO    ][14466] 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=ahf78e architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-10 10:02:06,377 [salt.loaded.ext.module.maas:412 ][WARNING ][14466] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-10 10:02:06,379 [salt.loaded.ext.module.maas:92  ][INFO    ][14466] 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=qn474m architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-10 10:02:07,590 [salt.state       :300 ][INFO    ][14466] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-10 10:02:07,590 [salt.state       :1951][INFO    ][14466] Completed state [maas.process_machines] at time 10:02:07.590728 duration_in_ms=6777.139
2019-05-10 10:02:07,594 [salt.minion      :1711][INFO    ][14466] Returning information for job: 20190510100152624212
2019-05-10 10:02:41,327 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510100241318609
2019-05-10 10:02:41,347 [salt.minion      :1432][INFO    ][14756] Starting a new job with PID 14756
2019-05-10 10:02:47,364 [salt.state       :915 ][INFO    ][14756] Loading fresh modules for state activity
2019-05-10 10:02:47,482 [salt.state       :1780][INFO    ][14756] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:02:47.482629
2019-05-10 10:02:47,483 [salt.state       :1813][INFO    ][14756] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 10:02:47,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14756] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 10:02:49,301 [salt.state       :300 ][INFO    ][14756] {'pid': 14768, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 10:02:49,302 [salt.state       :1951][INFO    ][14756] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:02:49.302413 duration_in_ms=1819.785
2019-05-10 10:02:49,306 [salt.state       :1780][INFO    ][14756] Running state [maas.wait_for_machine_status] at time 10:02:49.306225
2019-05-10 10:02:49,306 [salt.state       :1813][INFO    ][14756] Executing state module.run for [maas.wait_for_machine_status]
2019-05-10 10:02:49,307 [salt.utils.decorators:613 ][WARNING ][14756] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 10:02:52,160 [salt.state       :300 ][INFO    ][14756] {'ret': True}
2019-05-10 10:02:52,160 [salt.state       :1951][INFO    ][14756] Completed state [maas.wait_for_machine_status] at time 10:02:52.160561 duration_in_ms=2854.335
2019-05-10 10:02:52,164 [salt.minion      :1711][INFO    ][14756] Returning information for job: 20190510100241318609
2019-05-10 10:02:52,876 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510100252812734
2019-05-10 10:02:52,903 [salt.minion      :1432][INFO    ][14783] Starting a new job with PID 14783
2019-05-10 10:02:53,843 [salt.state       :915 ][INFO    ][14783] Loading fresh modules for state activity
2019-05-10 10:02:54,005 [salt.state       :1780][INFO    ][14783] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:02:54.005365
2019-05-10 10:02:54,006 [salt.state       :1813][INFO    ][14783] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 10:02:54,008 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14783] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 10:02:55,860 [salt.state       :300 ][INFO    ][14783] {'pid': 14790, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 10:02:55,862 [salt.state       :1951][INFO    ][14783] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:02:55.862113 duration_in_ms=1856.749
2019-05-10 10:02:55,865 [salt.state       :1780][INFO    ][14783] Running state [maas_machines_storage_cmp002_lvm] at time 10:02:55.865455
2019-05-10 10:02:55,866 [salt.state       :1813][INFO    ][14783] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-10 10:02:56,433 [salt.state       :300 ][INFO    ][14783] Machine cmp002 is not in Ready state.
2019-05-10 10:02:56,434 [salt.state       :1951][INFO    ][14783] Completed state [maas_machines_storage_cmp002_lvm] at time 10:02:56.434397 duration_in_ms=568.941
2019-05-10 10:02:56,435 [salt.state       :1780][INFO    ][14783] Running state [maas_machines_storage_cmp001_lvm] at time 10:02:56.435142
2019-05-10 10:02:56,435 [salt.state       :1813][INFO    ][14783] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-10 10:02:56,972 [salt.state       :300 ][INFO    ][14783] Machine cmp001 is not in Ready state.
2019-05-10 10:02:56,973 [salt.state       :1951][INFO    ][14783] Completed state [maas_machines_storage_cmp001_lvm] at time 10:02:56.972904 duration_in_ms=537.762
2019-05-10 10:02:56,977 [salt.minion      :1711][INFO    ][14783] Returning information for job: 20190510100252812734
2019-05-10 10:02:57,655 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510100257638760
2019-05-10 10:02:57,680 [salt.minion      :1432][INFO    ][14800] Starting a new job with PID 14800
2019-05-10 10:02:58,699 [salt.state       :915 ][INFO    ][14800] Loading fresh modules for state activity
2019-05-10 10:02:58,822 [salt.state       :1780][INFO    ][14800] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:02:58.822551
2019-05-10 10:02:58,822 [salt.state       :1813][INFO    ][14800] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 10:02:58,824 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14800] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 10:03:00,771 [salt.state       :300 ][INFO    ][14800] {'pid': 14807, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 10:03:00,773 [salt.state       :1951][INFO    ][14800] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:03:00.773034 duration_in_ms=1950.482
2019-05-10 10:03:00,776 [salt.state       :1780][INFO    ][14800] Running state [maas.deploy_machines] at time 10:03:00.776750
2019-05-10 10:03:00,777 [salt.state       :1813][INFO    ][14800] Executing state module.run for [maas.deploy_machines]
2019-05-10 10:03:00,778 [salt.utils.decorators:613 ][WARNING ][14800] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 10:03:01,362 [salt.loaded.ext.module.maas:684 ][INFO    ][14800] deploymachines hwe_kernel=hwe-16.04 system_id=qn474m distro_series=xenial
2019-05-10 10:03:03,787 [salt.state       :300 ][INFO    ][14800] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-10 10:03:03,787 [salt.state       :1951][INFO    ][14800] Completed state [maas.deploy_machines] at time 10:03:03.787637 duration_in_ms=3010.886
2019-05-10 10:03:03,791 [salt.minion      :1711][INFO    ][14800] Returning information for job: 20190510100257638760
2019-05-10 10:03:04,483 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190510100304472214
2019-05-10 10:03:04,511 [salt.minion      :1432][INFO    ][14871] Starting a new job with PID 14871
2019-05-10 10:03:10,553 [salt.state       :915 ][INFO    ][14871] Loading fresh modules for state activity
2019-05-10 10:03:10,676 [salt.state       :1780][INFO    ][14871] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:03:10.676772
2019-05-10 10:03:10,677 [salt.state       :1813][INFO    ][14871] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-10 10:03:10,679 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14871] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-10 10:03:12,535 [salt.state       :300 ][INFO    ][14871] {'pid': 14883, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-10 10:03:12,536 [salt.state       :1951][INFO    ][14871] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:03:12.536619 duration_in_ms=1859.846
2019-05-10 10:03:12,540 [salt.state       :1780][INFO    ][14871] Running state [maas.wait_for_machine_status] at time 10:03:12.540082
2019-05-10 10:03:12,540 [salt.state       :1813][INFO    ][14871] Executing state module.run for [maas.wait_for_machine_status]
2019-05-10 10:03:12,541 [salt.utils.decorators:613 ][WARNING ][14871] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-10 10:03:15,372 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.19203806s left)
2019-05-10 10:03:19,519 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100319506750
2019-05-10 10:03:19,547 [salt.minion      :1432][INFO    ][14911] Starting a new job with PID 14911
2019-05-10 10:03:19,575 [salt.minion      :1711][INFO    ][14911] Returning information for job: 20190510100319506750
2019-05-10 10:03:48,204 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.35998201s left)
2019-05-10 10:03:49,612 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100349599446
2019-05-10 10:03:49,639 [salt.minion      :1432][INFO    ][14962] Starting a new job with PID 14962
2019-05-10 10:03:49,668 [salt.minion      :1711][INFO    ][14962] Returning information for job: 20190510100349599446
2019-05-10 10:04:19,699 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100419687708
2019-05-10 10:04:19,722 [salt.minion      :1432][INFO    ][14997] Starting a new job with PID 14997
2019-05-10 10:04:19,753 [salt.minion      :1711][INFO    ][14997] Returning information for job: 20190510100419687708
2019-05-10 10:04:21,091 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.47350788s left)
2019-05-10 10:04:49,773 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100449765289
2019-05-10 10:04:49,796 [salt.minion      :1432][INFO    ][15057] Starting a new job with PID 15057
2019-05-10 10:04:49,824 [salt.minion      :1711][INFO    ][15057] Returning information for job: 20190510100449765289
2019-05-10 10:04:53,743 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.82127094s left)
2019-05-10 10:05:19,852 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100519840178
2019-05-10 10:05:19,877 [salt.minion      :1432][INFO    ][15088] Starting a new job with PID 15088
2019-05-10 10:05:19,909 [salt.minion      :1711][INFO    ][15088] Returning information for job: 20190510100519840178
2019-05-10 10:05:26,554 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.00974202s left)
2019-05-10 10:05:49,940 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100549930485
2019-05-10 10:05:49,966 [salt.minion      :1432][INFO    ][15141] Starting a new job with PID 15141
2019-05-10 10:05:49,995 [salt.minion      :1711][INFO    ][15141] Returning information for job: 20190510100549930485
2019-05-10 10:05:59,580 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.98413587s left)
2019-05-10 10:06:20,034 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100620020271
2019-05-10 10:06:20,065 [salt.minion      :1432][INFO    ][15169] Starting a new job with PID 15169
2019-05-10 10:06:20,095 [salt.minion      :1711][INFO    ][15169] Returning information for job: 20190510100620020271
2019-05-10 10:06:32,365 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.19917393s left)
2019-05-10 10:06:50,152 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100650134259
2019-05-10 10:06:50,181 [salt.minion      :1432][INFO    ][15216] Starting a new job with PID 15216
2019-05-10 10:06:50,212 [salt.minion      :1711][INFO    ][15216] Returning information for job: 20190510100650134259
2019-05-10 10:07:05,079 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.48491597s left)
2019-05-10 10:07:20,260 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100720250955
2019-05-10 10:07:20,286 [salt.minion      :1432][INFO    ][15270] Starting a new job with PID 15270
2019-05-10 10:07:20,315 [salt.minion      :1711][INFO    ][15270] Returning information for job: 20190510100720250955
2019-05-10 10:07:37,900 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.66434693s left)
2019-05-10 10:07:50,372 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100750356917
2019-05-10 10:07:50,399 [salt.minion      :1432][INFO    ][15333] Starting a new job with PID 15333
2019-05-10 10:07:50,437 [salt.minion      :1711][INFO    ][15333] Returning information for job: 20190510100750356917
2019-05-10 10:08:10,764 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.79982495s left)
2019-05-10 10:08:20,492 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100820477342
2019-05-10 10:08:20,524 [salt.minion      :1432][INFO    ][15383] Starting a new job with PID 15383
2019-05-10 10:08:20,567 [salt.minion      :1711][INFO    ][15383] Returning information for job: 20190510100820477342
2019-05-10 10:08:43,590 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.97438002s left)
2019-05-10 10:08:50,663 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100850646941
2019-05-10 10:08:50,696 [salt.minion      :1432][INFO    ][15574] Starting a new job with PID 15574
2019-05-10 10:08:50,728 [salt.minion      :1711][INFO    ][15574] Returning information for job: 20190510100850646941
2019-05-10 10:09:16,614 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.95017004s left)
2019-05-10 10:09:20,806 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100920789604
2019-05-10 10:09:20,836 [salt.minion      :1432][INFO    ][15608] Starting a new job with PID 15608
2019-05-10 10:09:20,872 [salt.minion      :1711][INFO    ][15608] Returning information for job: 20190510100920789604
2019-05-10 10:09:49,407 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.15738606s left)
2019-05-10 10:09:50,948 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510100950936811
2019-05-10 10:09:50,977 [salt.minion      :1432][INFO    ][15668] Starting a new job with PID 15668
2019-05-10 10:09:51,015 [salt.minion      :1711][INFO    ][15668] Returning information for job: 20190510100950936811
2019-05-10 10:10:21,093 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510101021084348
2019-05-10 10:10:21,128 [salt.minion      :1432][INFO    ][15696] Starting a new job with PID 15696
2019-05-10 10:10:21,163 [salt.minion      :1711][INFO    ][15696] Returning information for job: 20190510101021084348
2019-05-10 10:10:22,159 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1820.40521288s left)
2019-05-10 10:10:51,288 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510101051274344
2019-05-10 10:10:51,318 [salt.minion      :1432][INFO    ][15860] Starting a new job with PID 15860
2019-05-10 10:10:51,349 [salt.minion      :1711][INFO    ][15860] Returning information for job: 20190510101051274344
2019-05-10 10:10:55,074 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.48982s left)
2019-05-10 10:11:21,468 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510101121452071
2019-05-10 10:11:21,496 [salt.minion      :1432][INFO    ][15892] Starting a new job with PID 15892
2019-05-10 10:11:21,525 [salt.minion      :1711][INFO    ][15892] Returning information for job: 20190510101121452071
2019-05-10 10:11:27,800 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.76449895s left)
2019-05-10 10:11:51,643 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510101151629603
2019-05-10 10:11:51,669 [salt.minion      :1432][INFO    ][16006] Starting a new job with PID 16006
2019-05-10 10:11:51,702 [salt.minion      :1711][INFO    ][16006] Returning information for job: 20190510101151629603
2019-05-10 10:12:00,822 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.74238801s left)
2019-05-10 10:12:21,825 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510101221810153
2019-05-10 10:12:21,853 [salt.minion      :1432][INFO    ][16039] Starting a new job with PID 16039
2019-05-10 10:12:21,887 [salt.minion      :1711][INFO    ][16039] Returning information for job: 20190510101221810153
2019-05-10 10:12:33,558 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1689.00585604s left)
2019-05-10 10:12:51,993 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510101251978532
2019-05-10 10:12:52,020 [salt.minion      :1432][INFO    ][16086] Starting a new job with PID 16086
2019-05-10 10:12:52,048 [salt.minion      :1711][INFO    ][16086] Returning information for job: 20190510101251978532
2019-05-10 10:13:06,503 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1656.06118798s left)
2019-05-10 10:13:22,173 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510101322158886
2019-05-10 10:13:22,204 [salt.minion      :1432][INFO    ][16116] Starting a new job with PID 16116
2019-05-10 10:13:22,237 [salt.minion      :1711][INFO    ][16116] Returning information for job: 20190510101322158886
2019-05-10 10:13:39,079 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1623.48538589s left)
2019-05-10 10:13:52,357 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510101352342880
2019-05-10 10:13:52,388 [salt.minion      :1432][INFO    ][16164] Starting a new job with PID 16164
2019-05-10 10:13:52,419 [salt.minion      :1711][INFO    ][16164] Returning information for job: 20190510101352342880
2019-05-10 10:14:12,149 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1590.41517806s left)
2019-05-10 10:14:22,549 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510101422536535
2019-05-10 10:14:22,578 [salt.minion      :1432][INFO    ][16200] Starting a new job with PID 16200
2019-05-10 10:14:22,608 [salt.minion      :1711][INFO    ][16200] Returning information for job: 20190510101422536535
2019-05-10 10:14:44,990 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1557.57485604s left)
2019-05-10 10:14:52,748 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510101452732225
2019-05-10 10:14:52,774 [salt.minion      :1432][INFO    ][16406] Starting a new job with PID 16406
2019-05-10 10:14:52,804 [salt.minion      :1711][INFO    ][16406] Returning information for job: 20190510101452732225
2019-05-10 10:15:17,900 [salt.loaded.ext.module.maas:1023][INFO    ][14871] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1524.66437197s left)
2019-05-10 10:15:22,904 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190510101522888560
2019-05-10 10:15:22,927 [salt.minion      :1432][INFO    ][16471] Starting a new job with PID 16471
2019-05-10 10:15:22,956 [salt.minion      :1711][INFO    ][16471] Returning information for job: 20190510101522888560
2019-05-10 10:15:38,966 [salt.utils.schedule:1377][INFO    ][6045] Running scheduled job: __mine_interval
2019-05-10 10:15:50,535 [salt.state       :300 ][INFO    ][14871] {'ret': True}
2019-05-10 10:15:50,536 [salt.state       :1951][INFO    ][14871] Completed state [maas.wait_for_machine_status] at time 10:15:50.536078 duration_in_ms=757995.984
2019-05-10 10:15:50,540 [salt.minion      :1711][INFO    ][14871] Returning information for job: 20190510100304472214
2019-05-10 11:15:38,966 [salt.utils.schedule:1377][INFO    ][6045] Running scheduled job: __mine_interval
2019-05-10 11:41:33,799 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command cp.push_dir with jid 20190510114133784579
2019-05-10 11:41:33,826 [salt.minion      :1432][INFO    ][22836] Starting a new job with PID 22836
