2019-05-25 02:04:28,783 [salt.utils.decorators:613 ][WARNING ][2145] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:04:29,855 [salt.utils.decorators:613 ][WARNING ][2145] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:04:33,592 [salt.loaded.int.states.file:2298][WARNING ][2372] 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-25 02:04:57,023 [salt.state       :2022][WARNING ][2669] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-25 02:04:59,280 [salt.utils.decorators:613 ][WARNING ][2669] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:05:14,387 [salt.utils.decorators:613 ][WARNING ][2669] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:05:40,014 [salt.utils.decorators:613 ][WARNING ][2669] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:05:40,956 [salt.utils.decorators:613 ][WARNING ][2669] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:05:43,448 [salt.loaded.ext.module.maasng:1008][WARNING ][2669] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-25 02:05:43,449 [salt.loaded.ext.module.maasng:1011][WARNING ][2669] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-25 02:05:44,190 [salt.loaded.ext.module.maasng:1235][WARNING ][2669] Ignoring parameter vlan:0
2019-05-25 02:05:56,443 [salt.utils.decorators:613 ][WARNING ][4961] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:05:56,494 [salt.loaded.ext.module.maas:412 ][WARNING ][4961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 02:05:57,827 [salt.loaded.ext.module.maas:412 ][WARNING ][4961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 02:05:58,966 [salt.loaded.ext.module.maas:412 ][WARNING ][4961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 02:06:00,192 [salt.loaded.ext.module.maas:412 ][WARNING ][4961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 02:06:01,579 [salt.loaded.ext.module.maas:412 ][WARNING ][4961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 02:06:05,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5560] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-25 02:06:05,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5560] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-25 02:06:05,746 [salt.utils.parsers:1051][WARNING ][382] Minion received a SIGTERM. Exiting.
2019-05-25 02:06:06,864 [salt.cli.daemons :293 ][INFO    ][5616] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-25 02:06:07,026 [salt.cli.daemons :82  ][INFO    ][5616] Starting up the Salt Minion
2019-05-25 02:06:07,027 [salt.utils.event :1017][INFO    ][5616] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-25 02:06:08,304 [salt.minion      :976 ][INFO    ][5616] Creating minion process manager
2019-05-25 02:06:10,367 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5616] Executing command ['date', '+%z'] in directory '/root'
2019-05-25 02:06:10,392 [salt.utils.schedule:568 ][INFO    ][5616] Updating job settings for scheduled job: __mine_interval
2019-05-25 02:06:10,396 [salt.minion      :1108][INFO    ][5616] Added mine.update to scheduler
2019-05-25 02:06:10,400 [salt.minion      :1975][INFO    ][5616] Minion is starting as user 'root'
2019-05-25 02:06:10,420 [salt.minion      :2336][INFO    ][5616] Minion is ready to receive requests!
2019-05-25 02:06:34,084 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525020634073647
2019-05-25 02:06:34,110 [salt.minion      :1432][INFO    ][5710] Starting a new job with PID 5710
2019-05-25 02:06:41,814 [salt.state       :915 ][INFO    ][5710] Loading fresh modules for state activity
2019-05-25 02:06:41,867 [salt.fileclient  :1219][INFO    ][5710] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-25 02:06:41,920 [salt.state       :1780][INFO    ][5710] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:41.920659
2019-05-25 02:06:41,921 [salt.state       :1813][INFO    ][5710] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 02:06:41,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5710] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 02:06:43,701 [salt.state       :300 ][INFO    ][5710] {'pid': 5717, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 02:06:43,702 [salt.state       :1951][INFO    ][5710] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:43.702438 duration_in_ms=1781.779
2019-05-25 02:06:43,705 [salt.state       :1780][INFO    ][5710] Running state [maas.wait_for_machine_status] at time 02:06:43.705355
2019-05-25 02:06:43,707 [salt.state       :1813][INFO    ][5710] Executing state module.run for [maas.wait_for_machine_status]
2019-05-25 02:06:43,707 [salt.utils.decorators:613 ][WARNING ][5710] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:06:44,466 [salt.loaded.ext.module.maas:1023][INFO    ][5710] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.2510798s left)
2019-05-25 02:06:49,120 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525020649103614
2019-05-25 02:06:49,141 [salt.minion      :1432][INFO    ][5740] Starting a new job with PID 5740
2019-05-25 02:06:49,172 [salt.minion      :1711][INFO    ][5740] Returning information for job: 20190525020649103614
2019-05-25 02:07:15,241 [salt.loaded.ext.module.maas:1023][INFO    ][5710] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.4760859s left)
2019-05-25 02:07:19,182 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525020719170922
2019-05-25 02:07:19,204 [salt.minion      :1432][INFO    ][5786] Starting a new job with PID 5786
2019-05-25 02:07:19,230 [salt.minion      :1711][INFO    ][5786] Returning information for job: 20190525020719170922
2019-05-25 02:07:46,132 [salt.loaded.ext.module.maas:1023][INFO    ][5710] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.58516693s left)
2019-05-25 02:07:49,280 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525020749264929
2019-05-25 02:07:49,302 [salt.minion      :1432][INFO    ][5815] Starting a new job with PID 5815
2019-05-25 02:07:49,328 [salt.minion      :1711][INFO    ][5815] Returning information for job: 20190525020749264929
2019-05-25 02:08:17,093 [salt.loaded.ext.module.maas:1023][INFO    ][5710] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.62445784s left)
2019-05-25 02:08:19,349 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525020819334876
2019-05-25 02:08:19,376 [salt.minion      :1432][INFO    ][5863] Starting a new job with PID 5863
2019-05-25 02:08:19,401 [salt.minion      :1711][INFO    ][5863] Returning information for job: 20190525020819334876
2019-05-25 02:08:48,189 [salt.loaded.ext.module.maas:1023][INFO    ][5710] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.52842784s left)
2019-05-25 02:08:49,452 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525020849437639
2019-05-25 02:08:49,479 [salt.minion      :1432][INFO    ][5919] Starting a new job with PID 5919
2019-05-25 02:08:49,507 [salt.minion      :1711][INFO    ][5919] Returning information for job: 20190525020849437639
2019-05-25 02:09:19,363 [salt.loaded.ext.module.maas:1023][INFO    ][5710] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.35437989s left)
2019-05-25 02:09:19,571 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525020919559429
2019-05-25 02:09:19,590 [salt.minion      :1432][INFO    ][6108] Starting a new job with PID 6108
2019-05-25 02:09:19,618 [salt.minion      :1711][INFO    ][6108] Returning information for job: 20190525020919559429
2019-05-25 02:09:49,674 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525020949656394
2019-05-25 02:09:49,698 [salt.minion      :1432][INFO    ][6176] Starting a new job with PID 6176
2019-05-25 02:09:49,727 [salt.minion      :1711][INFO    ][6176] Returning information for job: 20190525020949656394
2019-05-25 02:09:50,776 [salt.loaded.ext.module.maas:1023][INFO    ][5710] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.94157982s left)
2019-05-25 02:10:19,809 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021019793731
2019-05-25 02:10:19,830 [salt.minion      :1432][INFO    ][6501] Starting a new job with PID 6501
2019-05-25 02:10:19,856 [salt.minion      :1711][INFO    ][6501] Returning information for job: 20190525021019793731
2019-05-25 02:10:22,191 [salt.loaded.ext.module.maas:1023][INFO    ][5710] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.52593684s left)
2019-05-25 02:10:49,909 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021049899716
2019-05-25 02:10:49,933 [salt.minion      :1432][INFO    ][6617] Starting a new job with PID 6617
2019-05-25 02:10:49,964 [salt.minion      :1711][INFO    ][6617] Returning information for job: 20190525021049899716
2019-05-25 02:10:53,949 [salt.loaded.ext.module.maas:1023][INFO    ][5710] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.76833797s left)
2019-05-25 02:11:20,083 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021120072235
2019-05-25 02:11:20,107 [salt.minion      :1432][INFO    ][7113] Starting a new job with PID 7113
2019-05-25 02:11:20,136 [salt.minion      :1711][INFO    ][7113] Returning information for job: 20190525021120072235
2019-05-25 02:11:26,239 [salt.loaded.ext.module.maas:1023][INFO    ][5710] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.478791s left)
2019-05-25 02:11:50,184 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021150169141
2019-05-25 02:11:50,213 [salt.minion      :1432][INFO    ][7170] Starting a new job with PID 7170
2019-05-25 02:11:50,242 [salt.minion      :1711][INFO    ][7170] Returning information for job: 20190525021150169141
2019-05-25 02:11:58,367 [salt.loaded.ext.module.maas:1023][INFO    ][5710] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.3505249s left)
2019-05-25 02:12:20,322 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021220309851
2019-05-25 02:12:20,344 [salt.minion      :1432][INFO    ][7380] Starting a new job with PID 7380
2019-05-25 02:12:20,372 [salt.minion      :1711][INFO    ][7380] Returning information for job: 20190525021220309851
2019-05-25 02:12:31,438 [salt.state       :300 ][INFO    ][5710] {'ret': True}
2019-05-25 02:12:31,438 [salt.state       :1951][INFO    ][5710] Completed state [maas.wait_for_machine_status] at time 02:12:31.438626 duration_in_ms=347733.27
2019-05-25 02:12:31,445 [salt.minion      :1711][INFO    ][5710] Returning information for job: 20190525020634073647
2019-05-25 02:12:32,140 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525021232128201
2019-05-25 02:12:32,165 [salt.minion      :1432][INFO    ][7397] Starting a new job with PID 7397
2019-05-25 02:12:40,388 [salt.state       :915 ][INFO    ][7397] Loading fresh modules for state activity
2019-05-25 02:12:40,457 [salt.fileclient  :1219][INFO    ][7397] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-25 02:12:40,559 [salt.state       :1780][INFO    ][7397] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:40.559523
2019-05-25 02:12:40,560 [salt.state       :1813][INFO    ][7397] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 02:12:40,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7397] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 02:12:42,586 [salt.state       :300 ][INFO    ][7397] {'pid': 7429, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 02:12:42,588 [salt.state       :1951][INFO    ][7397] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:42.587817 duration_in_ms=2028.29
2019-05-25 02:12:42,592 [salt.state       :1780][INFO    ][7397] Running state [maas_machines_storage_cmp002_lvm] at time 02:12:42.592341
2019-05-25 02:12:42,592 [salt.state       :1813][INFO    ][7397] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-25 02:12:43,943 [salt.loaded.ext.module.maasng:610 ][INFO    ][7397] r6de63
2019-05-25 02:12:43,943 [salt.loaded.ext.module.maasng:626 ][INFO    ][7397] sda
2019-05-25 02:12:44,495 [salt.loaded.ext.module.maasng:361 ][INFO    ][7397] r6de63
2019-05-25 02:12:44,588 [salt.loaded.ext.module.maasng:367 ][INFO    ][7397] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'fd6c3c75-86a1-46a7-87a0-5529d8780359', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r6de63', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'2057fcc7-a01f-4b31-afda-f679df2421c4', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/blockdevices/1/partition/1'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'r6de63', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/blockdevices/1/'}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'0d5fd0d2-9372-40ab-874e-b3e0d284d7ac', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'0f886dfe-ac28-4579-803c-a4900162cd41', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'r6de63', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'size': 800097042432, u'type': u'virtual', u'id': 3, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/blockdevices/3/'}]
2019-05-25 02:12:44,588 [salt.loaded.ext.module.maasng:632 ][INFO    ][7397] vgroot
2019-05-25 02:12:44,588 [salt.loaded.ext.module.maasng:635 ][INFO    ][7397] lvroot
2019-05-25 02:12:44,588 [salt.loaded.ext.module.maasng:639 ][INFO    ][7397] 107374182400
2019-05-25 02:12:45,208 [salt.loaded.ext.module.maasng:645 ][INFO    ][7397] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'1951a8e2-3668-4f04-96e9-803494836d91', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r6de63', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'ea910fce-7e24-4516-aedb-1d0caa92fb48', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'name': u'sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'r6de63', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 1, u'size': 800109715456}, u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'1951a8e2-3668-4f04-96e9-803494836d91', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r6de63', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'ea910fce-7e24-4516-aedb-1d0caa92fb48', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'name': u'sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'r6de63', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/blockdevices/11/', u'uuid': u'b5563a87-1fa2-4210-9e59-067a5ac64c8f', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'name': u'vgroot-lvroot', u'id_path': None, u'system_id': u'r6de63', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'fefea211-7811-4c97-bb1d-a6cdb72270b3', u'mount_options': None}, u'block_size': 4096, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/r6de63/', u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'address_ttl': None, u'system_id': u'r6de63', 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'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/blockdevices/11/', u'uuid': u'b5563a87-1fa2-4210-9e59-067a5ac64c8f', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'name': u'vgroot-lvroot', u'id_path': None, u'system_id': u'r6de63', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'fefea211-7811-4c97-bb1d-a6cdb72270b3', u'mount_options': None}, u'block_size': 4096, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'r6de63', u'id': 6}], 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': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'r6de63', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'r6de63', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'r6de63', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'id': 14, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'r6de63', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'id': 11, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'r6de63', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/interfaces/11/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'r6de63', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/blockdevices/1/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'1951a8e2-3668-4f04-96e9-803494836d91', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r6de63', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'ea910fce-7e24-4516-aedb-1d0caa92fb48', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}], u'name': u'sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'r6de63', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 1, u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'r6de63', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6de63/interfaces/4/'}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'commissioning_status_name': u'Passed', u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-25 02:12:45,212 [salt.state       :300 ][INFO    ][7397] {'new': {'storage_layout': 'lvm'}}
2019-05-25 02:12:45,212 [salt.state       :1951][INFO    ][7397] Completed state [maas_machines_storage_cmp002_lvm] at time 02:12:45.212441 duration_in_ms=2620.098
2019-05-25 02:12:45,212 [salt.state       :1780][INFO    ][7397] Running state [maas_machines_storage_cmp001_lvm] at time 02:12:45.212899
2019-05-25 02:12:45,213 [salt.state       :1813][INFO    ][7397] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-25 02:12:46,367 [salt.loaded.ext.module.maasng:610 ][INFO    ][7397] 4ebewe
2019-05-25 02:12:46,367 [salt.loaded.ext.module.maasng:626 ][INFO    ][7397] sda
2019-05-25 02:12:46,960 [salt.loaded.ext.module.maasng:361 ][INFO    ][7397] 4ebewe
2019-05-25 02:12:47,048 [salt.loaded.ext.module.maasng:367 ][INFO    ][7397] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'93c1349a-3af0-4e47-9338-5697fbbb9282', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4ebewe', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'be82aa3f-c052-4c0f-bbe7-713c7630f51f', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/blockdevices/2/partition/2'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'4ebewe', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/blockdevices/2/'}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'82d43fc4-81e6-4bcc-bef1-5fa71973dc02', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'6764e6f0-51fc-480f-9dc2-a7f56d650851', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4ebewe', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'size': 800097042432, u'type': u'virtual', u'id': 4, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/blockdevices/4/'}]
2019-05-25 02:12:47,049 [salt.loaded.ext.module.maasng:632 ][INFO    ][7397] vgroot
2019-05-25 02:12:47,049 [salt.loaded.ext.module.maasng:635 ][INFO    ][7397] lvroot
2019-05-25 02:12:47,050 [salt.loaded.ext.module.maasng:639 ][INFO    ][7397] 107374182400
2019-05-25 02:12:47,255 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021247240925
2019-05-25 02:12:47,276 [salt.minion      :1432][INFO    ][7456] Starting a new job with PID 7456
2019-05-25 02:12:47,304 [salt.minion      :1711][INFO    ][7456] Returning information for job: 20190525021247240925
2019-05-25 02:12:47,647 [salt.loaded.ext.module.maasng:645 ][INFO    ][7397] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'4f08a099-9adc-4270-aeb7-818046fcb68e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4ebewe', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5a422995-f83b-44ce-9bef-461534cb7bb1', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/blockdevices/2/partition/7'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'4ebewe', 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'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/blockdevices/2/'}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'4ebewe', 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'disable_ipv4': False, u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'4f08a099-9adc-4270-aeb7-818046fcb68e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4ebewe', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5a422995-f83b-44ce-9bef-461534cb7bb1', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/blockdevices/2/partition/7'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'4ebewe', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/blockdevices/2/'}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'f53d8413-9e8b-41a8-937a-521392ebf6b5', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'a10349de-53db-40a2-86a7-13aad8796fe0', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4ebewe', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'4f08a099-9adc-4270-aeb7-818046fcb68e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4ebewe', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5a422995-f83b-44ce-9bef-461534cb7bb1', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/blockdevices/2/partition/7'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'4ebewe', 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'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'4ebewe', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'f53d8413-9e8b-41a8-937a-521392ebf6b5', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'a10349de-53db-40a2-86a7-13aad8796fe0', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4ebewe', 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'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/blockdevices/12/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'system_id': u'4ebewe', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/interfaces/5/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'system_id': u'4ebewe', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'id': 17, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'effective_mtu': 1500, u'system_id': u'4ebewe', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/interfaces/17/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'id': 20, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'effective_mtu': 1500, u'system_id': u'4ebewe', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/interfaces/20/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'parents': [], u'id': 16, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'effective_mtu': 1500, u'system_id': u'4ebewe', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/interfaces/16/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'id': 18, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'effective_mtu': 1500, u'system_id': u'4ebewe', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'id': 19, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'effective_mtu': 1500, u'system_id': u'4ebewe', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4ebewe/interfaces/19/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/4ebewe/'}
2019-05-25 02:12:47,650 [salt.state       :300 ][INFO    ][7397] {'new': {'storage_layout': 'lvm'}}
2019-05-25 02:12:47,651 [salt.state       :1951][INFO    ][7397] Completed state [maas_machines_storage_cmp001_lvm] at time 02:12:47.650899 duration_in_ms=2437.998
2019-05-25 02:12:47,657 [salt.minion      :1711][INFO    ][7397] Returning information for job: 20190525021232128201
2019-05-25 02:12:48,332 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525021248318140
2019-05-25 02:12:48,358 [salt.minion      :1432][INFO    ][7463] Starting a new job with PID 7463
2019-05-25 02:12:49,459 [salt.state       :915 ][INFO    ][7463] Loading fresh modules for state activity
2019-05-25 02:12:49,530 [salt.fileclient  :1219][INFO    ][7463] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-25 02:12:49,590 [salt.state       :1780][INFO    ][7463] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:49.589976
2019-05-25 02:12:49,590 [salt.state       :1813][INFO    ][7463] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 02:12:49,592 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7463] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 02:12:51,504 [salt.state       :300 ][INFO    ][7463] {'pid': 7470, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 02:12:51,506 [salt.state       :1951][INFO    ][7463] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:51.506722 duration_in_ms=1916.747
2019-05-25 02:12:51,509 [salt.state       :1780][INFO    ][7463] Running state [maas.deploy_machines] at time 02:12:51.509473
2019-05-25 02:12:51,510 [salt.state       :1813][INFO    ][7463] Executing state module.run for [maas.deploy_machines]
2019-05-25 02:12:51,511 [salt.utils.decorators:613 ][WARNING ][7463] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:12:52,116 [salt.loaded.ext.module.maas:684 ][INFO    ][7463] deploymachines hwe_kernel=ga-16.04 system_id=r6de63 distro_series=xenial
2019-05-25 02:12:54,693 [salt.loaded.ext.module.maas:684 ][INFO    ][7463] deploymachines hwe_kernel=ga-16.04 system_id=4ebewe distro_series=xenial
2019-05-25 02:12:57,203 [salt.loaded.ext.module.maas:684 ][INFO    ][7463] deploymachines hwe_kernel=ga-16.04 system_id=hwepq4 distro_series=xenial
2019-05-25 02:12:59,756 [salt.loaded.ext.module.maas:684 ][INFO    ][7463] deploymachines hwe_kernel=ga-16.04 system_id=hb83gn distro_series=xenial
2019-05-25 02:13:02,457 [salt.loaded.ext.module.maas:684 ][INFO    ][7463] deploymachines hwe_kernel=ga-16.04 system_id=f7r4ds distro_series=xenial
2019-05-25 02:13:03,431 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021303423874
2019-05-25 02:13:03,454 [salt.minion      :1432][INFO    ][7740] Starting a new job with PID 7740
2019-05-25 02:13:03,484 [salt.minion      :1711][INFO    ][7740] Returning information for job: 20190525021303423874
2019-05-25 02:13:05,069 [salt.state       :300 ][INFO    ][7463] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-25 02:13:05,069 [salt.state       :1951][INFO    ][7463] Completed state [maas.deploy_machines] at time 02:13:05.069572 duration_in_ms=13560.099
2019-05-25 02:13:05,073 [salt.minion      :1711][INFO    ][7463] Returning information for job: 20190525021248318140
2019-05-25 02:13:05,780 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525021305769027
2019-05-25 02:13:05,805 [salt.minion      :1432][INFO    ][7779] Starting a new job with PID 7779
2019-05-25 02:13:13,591 [salt.state       :915 ][INFO    ][7779] Loading fresh modules for state activity
2019-05-25 02:13:13,650 [salt.fileclient  :1219][INFO    ][7779] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-25 02:13:13,712 [salt.state       :1780][INFO    ][7779] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:13.712564
2019-05-25 02:13:13,712 [salt.state       :1813][INFO    ][7779] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 02:13:13,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7779] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 02:13:15,620 [salt.state       :300 ][INFO    ][7779] {'pid': 7791, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 02:13:15,621 [salt.state       :1951][INFO    ][7779] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:15.621063 duration_in_ms=1908.498
2019-05-25 02:13:15,625 [salt.state       :1780][INFO    ][7779] Running state [maas.wait_for_machine_status] at time 02:13:15.625087
2019-05-25 02:13:15,627 [salt.state       :1813][INFO    ][7779] Executing state module.run for [maas.wait_for_machine_status]
2019-05-25 02:13:15,627 [salt.utils.decorators:613 ][WARNING ][7779] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:13:18,642 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.99827695s left)
2019-05-25 02:13:20,901 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021320885447
2019-05-25 02:13:20,923 [salt.minion      :1432][INFO    ][7812] Starting a new job with PID 7812
2019-05-25 02:13:20,952 [salt.minion      :1711][INFO    ][7812] Returning information for job: 20190525021320885447
2019-05-25 02:13:50,984 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021350969003
2019-05-25 02:13:51,007 [salt.minion      :1432][INFO    ][7841] Starting a new job with PID 7841
2019-05-25 02:13:51,034 [salt.minion      :1711][INFO    ][7841] Returning information for job: 20190525021350969003
2019-05-25 02:13:51,512 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.12842178s left)
2019-05-25 02:14:21,101 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021421086165
2019-05-25 02:14:21,129 [salt.minion      :1432][INFO    ][7884] Starting a new job with PID 7884
2019-05-25 02:14:21,159 [salt.minion      :1711][INFO    ][7884] Returning information for job: 20190525021421086165
2019-05-25 02:14:24,501 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.13897395s left)
2019-05-25 02:14:51,196 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021451180881
2019-05-25 02:14:51,222 [salt.minion      :1432][INFO    ][7918] Starting a new job with PID 7918
2019-05-25 02:14:51,249 [salt.minion      :1711][INFO    ][7918] Returning information for job: 20190525021451180881
2019-05-25 02:14:57,501 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.1394558s left)
2019-05-25 02:15:21,292 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021521275926
2019-05-25 02:15:21,316 [salt.minion      :1432][INFO    ][7981] Starting a new job with PID 7981
2019-05-25 02:15:21,344 [salt.minion      :1711][INFO    ][7981] Returning information for job: 20190525021521275926
2019-05-25 02:15:30,473 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.16746092s left)
2019-05-25 02:15:51,381 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021551370569
2019-05-25 02:15:51,401 [salt.minion      :1432][INFO    ][8071] Starting a new job with PID 8071
2019-05-25 02:15:51,428 [salt.minion      :1711][INFO    ][8071] Returning information for job: 20190525021551370569
2019-05-25 02:16:03,741 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.89873886s left)
2019-05-25 02:16:21,491 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021621474487
2019-05-25 02:16:21,517 [salt.minion      :1432][INFO    ][8193] Starting a new job with PID 8193
2019-05-25 02:16:21,545 [salt.minion      :1711][INFO    ][8193] Returning information for job: 20190525021621474487
2019-05-25 02:16:36,688 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.95219088s left)
2019-05-25 02:16:51,596 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021651582106
2019-05-25 02:16:51,628 [salt.minion      :1432][INFO    ][8228] Starting a new job with PID 8228
2019-05-25 02:16:51,655 [salt.minion      :1711][INFO    ][8228] Returning information for job: 20190525021651582106
2019-05-25 02:17:09,745 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.89554s left)
2019-05-25 02:17:21,730 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021721715167
2019-05-25 02:17:21,755 [salt.minion      :1432][INFO    ][8494] Starting a new job with PID 8494
2019-05-25 02:17:21,783 [salt.minion      :1711][INFO    ][8494] Returning information for job: 20190525021721715167
2019-05-25 02:17:42,931 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.70887685s left)
2019-05-25 02:17:51,848 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021751832791
2019-05-25 02:17:51,880 [salt.minion      :1432][INFO    ][8597] Starting a new job with PID 8597
2019-05-25 02:17:51,907 [salt.minion      :1711][INFO    ][8597] Returning information for job: 20190525021751832791
2019-05-25 02:18:15,940 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.70035696s left)
2019-05-25 02:18:22,067 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021822052270
2019-05-25 02:18:22,090 [salt.minion      :1432][INFO    ][9068] Starting a new job with PID 9068
2019-05-25 02:18:22,114 [salt.minion      :1711][INFO    ][9068] Returning information for job: 20190525021822052270
2019-05-25 02:18:48,903 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.73704195s left)
2019-05-25 02:18:52,185 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021852173810
2019-05-25 02:18:52,208 [salt.minion      :1432][INFO    ][9101] Starting a new job with PID 9101
2019-05-25 02:18:52,237 [salt.minion      :1711][INFO    ][9101] Returning information for job: 20190525021852173810
2019-05-25 02:19:21,948 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.69201779s left)
2019-05-25 02:19:22,375 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021922359232
2019-05-25 02:19:22,403 [salt.minion      :1432][INFO    ][9399] Starting a new job with PID 9399
2019-05-25 02:19:22,432 [salt.minion      :1711][INFO    ][9399] Returning information for job: 20190525021922359232
2019-05-25 02:19:52,520 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525021952509382
2019-05-25 02:19:52,545 [salt.minion      :1432][INFO    ][9439] Starting a new job with PID 9439
2019-05-25 02:19:52,576 [salt.minion      :1711][INFO    ][9439] Returning information for job: 20190525021952509382
2019-05-25 02:19:55,121 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.51894379s left)
2019-05-25 02:20:22,699 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022022684175
2019-05-25 02:20:22,723 [salt.minion      :1432][INFO    ][9769] Starting a new job with PID 9769
2019-05-25 02:20:22,753 [salt.minion      :1711][INFO    ][9769] Returning information for job: 20190525022022684175
2019-05-25 02:20:28,247 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.39273286s left)
2019-05-25 02:20:52,849 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022052834388
2019-05-25 02:20:52,879 [salt.minion      :1432][INFO    ][9808] Starting a new job with PID 9808
2019-05-25 02:20:52,913 [salt.minion      :1711][INFO    ][9808] Returning information for job: 20190525022052834388
2019-05-25 02:21:02,112 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.5276649s left)
2019-05-25 02:21:23,040 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022123027425
2019-05-25 02:21:23,064 [salt.minion      :1432][INFO    ][9987] Starting a new job with PID 9987
2019-05-25 02:21:23,096 [salt.minion      :1711][INFO    ][9987] Returning information for job: 20190525022123027425
2019-05-25 02:21:34,900 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.74017882s left)
2019-05-25 02:21:53,194 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022153177348
2019-05-25 02:21:53,224 [salt.minion      :1432][INFO    ][10043] Starting a new job with PID 10043
2019-05-25 02:21:53,252 [salt.minion      :1711][INFO    ][10043] Returning information for job: 20190525022153177348
2019-05-25 02:22:07,970 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.66959381s left)
2019-05-25 02:22:23,333 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022223313191
2019-05-25 02:22:23,358 [salt.minion      :1432][INFO    ][10150] Starting a new job with PID 10150
2019-05-25 02:22:23,388 [salt.minion      :1711][INFO    ][10150] Returning information for job: 20190525022223313191
2019-05-25 02:22:41,001 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.63979292s left)
2019-05-25 02:22:53,419 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022253402641
2019-05-25 02:22:53,448 [salt.minion      :1432][INFO    ][10203] Starting a new job with PID 10203
2019-05-25 02:22:53,476 [salt.minion      :1711][INFO    ][10203] Returning information for job: 20190525022253402641
2019-05-25 02:23:14,023 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.61714387s left)
2019-05-25 02:23:23,623 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022323606990
2019-05-25 02:23:23,649 [salt.minion      :1432][INFO    ][10450] Starting a new job with PID 10450
2019-05-25 02:23:23,676 [salt.minion      :1711][INFO    ][10450] Returning information for job: 20190525022323606990
2019-05-25 02:23:46,873 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.76727986s left)
2019-05-25 02:23:53,794 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022353782583
2019-05-25 02:23:53,825 [salt.minion      :1432][INFO    ][10480] Starting a new job with PID 10480
2019-05-25 02:23:53,854 [salt.minion      :1711][INFO    ][10480] Returning information for job: 20190525022353782583
2019-05-25 02:24:19,888 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.75239086s left)
2019-05-25 02:24:23,987 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022423971832
2019-05-25 02:24:24,011 [salt.minion      :1432][INFO    ][10526] Starting a new job with PID 10526
2019-05-25 02:24:24,044 [salt.minion      :1711][INFO    ][10526] Returning information for job: 20190525022423971832
2019-05-25 02:24:52,747 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.89271092s left)
2019-05-25 02:24:54,166 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022454150457
2019-05-25 02:24:54,192 [salt.minion      :1432][INFO    ][10690] Starting a new job with PID 10690
2019-05-25 02:24:54,220 [salt.minion      :1711][INFO    ][10690] Returning information for job: 20190525022454150457
2019-05-25 02:25:24,378 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022524365273
2019-05-25 02:25:24,401 [salt.minion      :1432][INFO    ][10807] Starting a new job with PID 10807
2019-05-25 02:25:24,433 [salt.minion      :1711][INFO    ][10807] Returning information for job: 20190525022524365273
2019-05-25 02:25:26,056 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1519.58361888s left)
2019-05-25 02:25:54,520 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022554503501
2019-05-25 02:25:54,550 [salt.minion      :1432][INFO    ][10869] Starting a new job with PID 10869
2019-05-25 02:25:54,590 [salt.minion      :1711][INFO    ][10869] Returning information for job: 20190525022554503501
2019-05-25 02:25:58,900 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1486.73973489s left)
2019-05-25 02:26:24,596 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022624580702
2019-05-25 02:26:24,627 [salt.minion      :1432][INFO    ][11132] Starting a new job with PID 11132
2019-05-25 02:26:24,658 [salt.minion      :1711][INFO    ][11132] Returning information for job: 20190525022624580702
2019-05-25 02:26:31,787 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.85275078s left)
2019-05-25 02:26:54,628 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022654617454
2019-05-25 02:26:54,645 [salt.minion      :1432][INFO    ][11163] Starting a new job with PID 11163
2019-05-25 02:26:54,673 [salt.minion      :1711][INFO    ][11163] Returning information for job: 20190525022654617454
2019-05-25 02:27:04,803 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.8370688s left)
2019-05-25 02:27:24,665 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022724648758
2019-05-25 02:27:24,695 [salt.minion      :1432][INFO    ][11208] Starting a new job with PID 11208
2019-05-25 02:27:24,724 [salt.minion      :1711][INFO    ][11208] Returning information for job: 20190525022724648758
2019-05-25 02:27:37,809 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.83084679s left)
2019-05-25 02:27:54,727 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022754716180
2019-05-25 02:27:54,750 [salt.minion      :1432][INFO    ][11244] Starting a new job with PID 11244
2019-05-25 02:27:54,779 [salt.minion      :1711][INFO    ][11244] Returning information for job: 20190525022754716180
2019-05-25 02:28:10,607 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.03337979s left)
2019-05-25 02:28:24,775 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022824761079
2019-05-25 02:28:24,803 [salt.minion      :1432][INFO    ][11290] Starting a new job with PID 11290
2019-05-25 02:28:24,832 [salt.minion      :1711][INFO    ][11290] Returning information for job: 20190525022824761079
2019-05-25 02:28:43,412 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1322.22789192s left)
2019-05-25 02:28:54,871 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022854856154
2019-05-25 02:28:54,903 [salt.minion      :1432][INFO    ][11323] Starting a new job with PID 11323
2019-05-25 02:28:54,935 [salt.minion      :1711][INFO    ][11323] Returning information for job: 20190525022854856154
2019-05-25 02:29:16,357 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.28303599s left)
2019-05-25 02:29:24,955 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022924940698
2019-05-25 02:29:24,981 [salt.minion      :1432][INFO    ][11371] Starting a new job with PID 11371
2019-05-25 02:29:25,014 [salt.minion      :1711][INFO    ][11371] Returning information for job: 20190525022924940698
2019-05-25 02:29:49,256 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.38393998s left)
2019-05-25 02:29:55,063 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525022955047274
2019-05-25 02:29:55,091 [salt.minion      :1432][INFO    ][11401] Starting a new job with PID 11401
2019-05-25 02:29:55,121 [salt.minion      :1711][INFO    ][11401] Returning information for job: 20190525022955047274
2019-05-25 02:30:22,126 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.51365185s left)
2019-05-25 02:30:25,176 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023025163397
2019-05-25 02:30:25,199 [salt.minion      :1432][INFO    ][11449] Starting a new job with PID 11449
2019-05-25 02:30:25,230 [salt.minion      :1711][INFO    ][11449] Returning information for job: 20190525023025163397
2019-05-25 02:30:54,910 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.73050094s left)
2019-05-25 02:30:55,315 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023055300521
2019-05-25 02:30:55,349 [salt.minion      :1432][INFO    ][11490] Starting a new job with PID 11490
2019-05-25 02:30:55,382 [salt.minion      :1711][INFO    ][11490] Returning information for job: 20190525023055300521
2019-05-25 02:31:25,474 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023125464627
2019-05-25 02:31:25,499 [salt.minion      :1432][INFO    ][11536] Starting a new job with PID 11536
2019-05-25 02:31:25,532 [salt.minion      :1711][INFO    ][11536] Returning information for job: 20190525023125464627
2019-05-25 02:31:27,921 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.71949077s left)
2019-05-25 02:31:55,621 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023155605552
2019-05-25 02:31:55,652 [salt.minion      :1432][INFO    ][11563] Starting a new job with PID 11563
2019-05-25 02:31:55,683 [salt.minion      :1711][INFO    ][11563] Returning information for job: 20190525023155605552
2019-05-25 02:32:00,712 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.92775583s left)
2019-05-25 02:32:25,768 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023225760549
2019-05-25 02:32:25,794 [salt.minion      :1432][INFO    ][11610] Starting a new job with PID 11610
2019-05-25 02:32:25,822 [salt.minion      :1711][INFO    ][11610] Returning information for job: 20190525023225760549
2019-05-25 02:32:33,491 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1092.14958382s left)
2019-05-25 02:32:55,937 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023255926032
2019-05-25 02:32:55,964 [salt.minion      :1432][INFO    ][11641] Starting a new job with PID 11641
2019-05-25 02:32:55,993 [salt.minion      :1711][INFO    ][11641] Returning information for job: 20190525023255926032
2019-05-25 02:33:06,375 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1059.26460886s left)
2019-05-25 02:33:26,132 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023326120145
2019-05-25 02:33:26,158 [salt.minion      :1432][INFO    ][11687] Starting a new job with PID 11687
2019-05-25 02:33:26,188 [salt.minion      :1711][INFO    ][11687] Returning information for job: 20190525023326120145
2019-05-25 02:33:39,234 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1026.40574694s left)
2019-05-25 02:33:56,313 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023356303592
2019-05-25 02:33:56,338 [salt.minion      :1432][INFO    ][11715] Starting a new job with PID 11715
2019-05-25 02:33:56,370 [salt.minion      :1711][INFO    ][11715] Returning information for job: 20190525023356303592
2019-05-25 02:34:12,024 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.615731955s left)
2019-05-25 02:34:26,336 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023426323603
2019-05-25 02:34:26,363 [salt.minion      :1432][INFO    ][11763] Starting a new job with PID 11763
2019-05-25 02:34:26,394 [salt.minion      :1711][INFO    ][11763] Returning information for job: 20190525023426323603
2019-05-25 02:34:45,087 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.552590847s left)
2019-05-25 02:34:56,368 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023456354612
2019-05-25 02:34:56,393 [salt.minion      :1432][INFO    ][11804] Starting a new job with PID 11804
2019-05-25 02:34:56,424 [salt.minion      :1711][INFO    ][11804] Returning information for job: 20190525023456354612
2019-05-25 02:35:17,773 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.867342949s left)
2019-05-25 02:35:26,424 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023526410786
2019-05-25 02:35:26,445 [salt.minion      :1432][INFO    ][11863] Starting a new job with PID 11863
2019-05-25 02:35:26,475 [salt.minion      :1711][INFO    ][11863] Returning information for job: 20190525023526410786
2019-05-25 02:35:50,497 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (895.143148899s left)
2019-05-25 02:35:56,448 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023556432188
2019-05-25 02:35:56,478 [salt.minion      :1432][INFO    ][11898] Starting a new job with PID 11898
2019-05-25 02:35:56,507 [salt.minion      :1711][INFO    ][11898] Returning information for job: 20190525023556432188
2019-05-25 02:36:23,324 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (862.316612959s left)
2019-05-25 02:36:26,520 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023626504207
2019-05-25 02:36:26,550 [salt.minion      :1432][INFO    ][11943] Starting a new job with PID 11943
2019-05-25 02:36:26,579 [salt.minion      :1711][INFO    ][11943] Returning information for job: 20190525023626504207
2019-05-25 02:36:56,080 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (829.560106993s left)
2019-05-25 02:36:56,596 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023656572427
2019-05-25 02:36:56,625 [salt.minion      :1432][INFO    ][11973] Starting a new job with PID 11973
2019-05-25 02:36:56,656 [salt.minion      :1711][INFO    ][11973] Returning information for job: 20190525023656572427
2019-05-25 02:37:26,734 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023726721065
2019-05-25 02:37:26,756 [salt.minion      :1432][INFO    ][12015] Starting a new job with PID 12015
2019-05-25 02:37:26,784 [salt.minion      :1711][INFO    ][12015] Returning information for job: 20190525023726721065
2019-05-25 02:37:28,925 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (796.715496778s left)
2019-05-25 02:37:56,839 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023756818986
2019-05-25 02:37:56,854 [salt.minion      :1432][INFO    ][12041] Starting a new job with PID 12041
2019-05-25 02:37:56,884 [salt.minion      :1711][INFO    ][12041] Returning information for job: 20190525023756818986
2019-05-25 02:38:01,872 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (763.768296957s left)
2019-05-25 02:38:26,989 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023826970968
2019-05-25 02:38:27,016 [salt.minion      :1432][INFO    ][12090] Starting a new job with PID 12090
2019-05-25 02:38:27,046 [salt.minion      :1711][INFO    ][12090] Returning information for job: 20190525023826970968
2019-05-25 02:38:34,808 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (730.832434893s left)
2019-05-25 02:38:57,151 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023857142984
2019-05-25 02:38:57,169 [salt.minion      :1432][INFO    ][12120] Starting a new job with PID 12120
2019-05-25 02:38:57,200 [salt.minion      :1711][INFO    ][12120] Returning information for job: 20190525023857142984
2019-05-25 02:39:07,714 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.92691493s left)
2019-05-25 02:39:27,316 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023927301209
2019-05-25 02:39:27,344 [salt.minion      :1432][INFO    ][12170] Starting a new job with PID 12170
2019-05-25 02:39:27,373 [salt.minion      :1711][INFO    ][12170] Returning information for job: 20190525023927301209
2019-05-25 02:39:40,495 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (665.145537853s left)
2019-05-25 02:39:57,526 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525023957516828
2019-05-25 02:39:57,551 [salt.minion      :1432][INFO    ][12206] Starting a new job with PID 12206
2019-05-25 02:39:57,581 [salt.minion      :1711][INFO    ][12206] Returning information for job: 20190525023957516828
2019-05-25 02:40:13,276 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (632.364172935s left)
2019-05-25 02:40:27,730 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024027712561
2019-05-25 02:40:27,753 [salt.minion      :1432][INFO    ][12251] Starting a new job with PID 12251
2019-05-25 02:40:27,785 [salt.minion      :1711][INFO    ][12251] Returning information for job: 20190525024027712561
2019-05-25 02:40:46,164 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (599.476223946s left)
2019-05-25 02:40:57,787 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024057773135
2019-05-25 02:40:57,817 [salt.minion      :1432][INFO    ][12283] Starting a new job with PID 12283
2019-05-25 02:40:57,849 [salt.minion      :1711][INFO    ][12283] Returning information for job: 20190525024057773135
2019-05-25 02:41:18,828 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (566.811903954s left)
2019-05-25 02:41:27,836 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024127823271
2019-05-25 02:41:27,863 [salt.minion      :1432][INFO    ][12329] Starting a new job with PID 12329
2019-05-25 02:41:27,904 [salt.minion      :1711][INFO    ][12329] Returning information for job: 20190525024127823271
2019-05-25 02:41:51,767 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (533.87287879s left)
2019-05-25 02:41:57,933 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024157918615
2019-05-25 02:41:57,964 [salt.minion      :1432][INFO    ][12358] Starting a new job with PID 12358
2019-05-25 02:41:57,994 [salt.minion      :1711][INFO    ][12358] Returning information for job: 20190525024157918615
2019-05-25 02:42:24,657 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (500.983426809s left)
2019-05-25 02:42:28,004 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024227994243
2019-05-25 02:42:28,029 [salt.minion      :1432][INFO    ][12404] Starting a new job with PID 12404
2019-05-25 02:42:28,057 [salt.minion      :1711][INFO    ][12404] Returning information for job: 20190525024227994243
2019-05-25 02:42:57,400 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (468.239748955s left)
2019-05-25 02:42:58,150 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024258143064
2019-05-25 02:42:58,172 [salt.minion      :1432][INFO    ][12433] Starting a new job with PID 12433
2019-05-25 02:42:58,204 [salt.minion      :1711][INFO    ][12433] Returning information for job: 20190525024258143064
2019-05-25 02:43:28,315 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024328296972
2019-05-25 02:43:28,338 [salt.minion      :1432][INFO    ][12475] Starting a new job with PID 12475
2019-05-25 02:43:28,373 [salt.minion      :1711][INFO    ][12475] Returning information for job: 20190525024328296972
2019-05-25 02:43:30,359 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (435.281268835s left)
2019-05-25 02:43:58,484 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024358471761
2019-05-25 02:43:58,512 [salt.minion      :1432][INFO    ][12504] Starting a new job with PID 12504
2019-05-25 02:43:58,542 [salt.minion      :1711][INFO    ][12504] Returning information for job: 20190525024358471761
2019-05-25 02:44:03,379 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (402.260962963s left)
2019-05-25 02:44:28,624 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024428615620
2019-05-25 02:44:28,648 [salt.minion      :1432][INFO    ][12552] Starting a new job with PID 12552
2019-05-25 02:44:28,679 [salt.minion      :1711][INFO    ][12552] Returning information for job: 20190525024428615620
2019-05-25 02:44:36,101 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (369.539320946s left)
2019-05-25 02:44:58,796 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024458779752
2019-05-25 02:44:58,824 [salt.minion      :1432][INFO    ][12719] Starting a new job with PID 12719
2019-05-25 02:44:58,855 [salt.minion      :1711][INFO    ][12719] Returning information for job: 20190525024458779752
2019-05-25 02:45:09,018 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (336.621994972s left)
2019-05-25 02:45:28,854 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024528840719
2019-05-25 02:45:28,883 [salt.minion      :1432][INFO    ][12772] Starting a new job with PID 12772
2019-05-25 02:45:28,912 [salt.minion      :1711][INFO    ][12772] Returning information for job: 20190525024528840719
2019-05-25 02:45:41,781 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (303.859120846s left)
2019-05-25 02:45:58,896 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024558880134
2019-05-25 02:45:58,927 [salt.minion      :1432][INFO    ][12805] Starting a new job with PID 12805
2019-05-25 02:45:58,955 [salt.minion      :1711][INFO    ][12805] Returning information for job: 20190525024558880134
2019-05-25 02:46:14,802 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (270.838056803s left)
2019-05-25 02:46:28,951 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024628936356
2019-05-25 02:46:28,979 [salt.minion      :1432][INFO    ][12848] Starting a new job with PID 12848
2019-05-25 02:46:29,011 [salt.minion      :1711][INFO    ][12848] Returning information for job: 20190525024628936356
2019-05-25 02:46:47,644 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (237.996188879s left)
2019-05-25 02:46:58,999 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024658983154
2019-05-25 02:46:59,025 [salt.minion      :1432][INFO    ][12879] Starting a new job with PID 12879
2019-05-25 02:46:59,059 [salt.minion      :1711][INFO    ][12879] Returning information for job: 20190525024658983154
2019-05-25 02:47:20,382 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (205.258033991s left)
2019-05-25 02:47:29,121 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024729108058
2019-05-25 02:47:29,154 [salt.minion      :1432][INFO    ][12923] Starting a new job with PID 12923
2019-05-25 02:47:29,183 [salt.minion      :1711][INFO    ][12923] Returning information for job: 20190525024729108058
2019-05-25 02:47:53,308 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (172.331838846s left)
2019-05-25 02:47:59,181 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024759164826
2019-05-25 02:47:59,209 [salt.minion      :1432][INFO    ][12952] Starting a new job with PID 12952
2019-05-25 02:47:59,237 [salt.minion      :1711][INFO    ][12952] Returning information for job: 20190525024759164826
2019-05-25 02:48:26,304 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (139.335814953s left)
2019-05-25 02:48:29,323 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024829308533
2019-05-25 02:48:29,348 [salt.minion      :1432][INFO    ][13000] Starting a new job with PID 13000
2019-05-25 02:48:29,380 [salt.minion      :1711][INFO    ][13000] Returning information for job: 20190525024829308533
2019-05-25 02:48:59,132 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (106.508123875s left)
2019-05-25 02:48:59,380 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024859361615
2019-05-25 02:48:59,404 [salt.minion      :1432][INFO    ][13032] Starting a new job with PID 13032
2019-05-25 02:48:59,434 [salt.minion      :1711][INFO    ][13032] Returning information for job: 20190525024859361615
2019-05-25 02:49:29,526 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024929514979
2019-05-25 02:49:29,552 [salt.minion      :1432][INFO    ][13076] Starting a new job with PID 13076
2019-05-25 02:49:29,590 [salt.minion      :1711][INFO    ][13076] Returning information for job: 20190525024929514979
2019-05-25 02:49:31,969 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (73.6714267731s left)
2019-05-25 02:49:59,740 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525024959731415
2019-05-25 02:49:59,757 [salt.minion      :1432][INFO    ][13107] Starting a new job with PID 13107
2019-05-25 02:49:59,788 [salt.minion      :1711][INFO    ][13107] Returning information for job: 20190525024959731415
2019-05-25 02:50:05,010 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (40.6299939156s left)
2019-05-25 02:50:29,917 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025029900162
2019-05-25 02:50:29,947 [salt.minion      :1432][INFO    ][13153] Starting a new job with PID 13153
2019-05-25 02:50:29,977 [salt.minion      :1711][INFO    ][13153] Returning information for job: 20190525025029900162
2019-05-25 02:50:37,702 [salt.loaded.ext.module.maas:1023][INFO    ][7779] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (7.93852782249s left)
2019-05-25 02:51:00,142 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025100126671
2019-05-25 02:51:00,172 [salt.minion      :1432][INFO    ][13185] Starting a new job with PID 13185
2019-05-25 02:51:00,200 [salt.minion      :1711][INFO    ][13185] Returning information for job: 20190525025100126671
2019-05-25 02:51:10,661 [salt.state       :302 ][ERROR   ][7779] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-25 02:51:10,662 [salt.state       :1951][INFO    ][7779] Completed state [maas.wait_for_machine_status] at time 02:51:10.662483 duration_in_ms=2275037.394
2019-05-25 02:51:10,666 [salt.minion      :1711][INFO    ][7779] Returning information for job: 20190525021305769027
2019-05-25 02:51:21,615 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command pillar.get with jid 20190525025121601661
2019-05-25 02:51:21,643 [salt.minion      :1432][INFO    ][13230] Starting a new job with PID 13230
2019-05-25 02:51:21,653 [salt.minion      :1711][INFO    ][13230] Returning information for job: 20190525025121601661
2019-05-25 02:51:22,375 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command service.status with jid 20190525025122360258
2019-05-25 02:51:22,401 [salt.minion      :1432][INFO    ][13235] Starting a new job with PID 13235
2019-05-25 02:51:23,386 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13235] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-25 02:51:23,432 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13235] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-25 02:51:23,452 [salt.minion      :1711][INFO    ][13235] Returning information for job: 20190525025122360258
2019-05-25 02:51:24,151 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525025124137891
2019-05-25 02:51:24,178 [salt.minion      :1432][INFO    ][13246] Starting a new job with PID 13246
2019-05-25 02:51:31,530 [salt.state       :915 ][INFO    ][13246] Loading fresh modules for state activity
2019-05-25 02:51:32,165 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command 'salt-minion --version' in directory '/root'
2019-05-25 02:51:32,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command 'salt-minion --version' in directory '/root'
2019-05-25 02:51:33,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command 'salt-minion --version' in directory '/root'
2019-05-25 02:51:33,992 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command 'salt-minion --version' in directory '/root'
2019-05-25 02:51:36,204 [salt.state       :1780][INFO    ][13246] Running state [salt-minion] at time 02:51:36.204814
2019-05-25 02:51:36,205 [salt.state       :1813][INFO    ][13246] Executing state pkg.installed for [salt-minion]
2019-05-25 02:51:36,206 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-25 02:51:36,333 [salt.state       :300 ][INFO    ][13246] All specified packages are already installed
2019-05-25 02:51:36,333 [salt.state       :1951][INFO    ][13246] Completed state [salt-minion] at time 02:51:36.333536 duration_in_ms=128.723
2019-05-25 02:51:36,334 [salt.state       :1780][INFO    ][13246] Running state [salt_minion_dependency_packages] at time 02:51:36.333950
2019-05-25 02:51:36,334 [salt.state       :1813][INFO    ][13246] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-25 02:51:36,344 [salt.state       :300 ][INFO    ][13246] All specified packages are already installed
2019-05-25 02:51:36,344 [salt.state       :1951][INFO    ][13246] Completed state [salt_minion_dependency_packages] at time 02:51:36.344458 duration_in_ms=10.507
2019-05-25 02:51:36,347 [salt.state       :1780][INFO    ][13246] Running state [/etc/salt/minion.d/minion.conf] at time 02:51:36.347936
2019-05-25 02:51:36,348 [salt.state       :1813][INFO    ][13246] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-25 02:51:36,652 [salt.state       :300 ][INFO    ][13246] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-25 02:51:36,653 [salt.state       :1951][INFO    ][13246] Completed state [/etc/salt/minion.d/minion.conf] at time 02:51:36.652912 duration_in_ms=304.975
2019-05-25 02:51:36,653 [salt.state       :1780][INFO    ][13246] Running state [python-netaddr] at time 02:51:36.653453
2019-05-25 02:51:36,654 [salt.state       :1813][INFO    ][13246] Executing state pkg.installed for [python-netaddr]
2019-05-25 02:51:36,668 [salt.state       :300 ][INFO    ][13246] All specified packages are already installed
2019-05-25 02:51:36,668 [salt.state       :1951][INFO    ][13246] Completed state [python-netaddr] at time 02:51:36.668354 duration_in_ms=14.902
2019-05-25 02:51:36,672 [salt.state       :1780][INFO    ][13246] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:36.672106
2019-05-25 02:51:36,672 [salt.state       :1813][INFO    ][13246] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-25 02:51:36,688 [salt.state       :300 ][INFO    ][13246] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-25 02:51:36,688 [salt.state       :1951][INFO    ][13246] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:36.688507 duration_in_ms=16.401
2019-05-25 02:51:36,690 [salt.state       :1780][INFO    ][13246] Running state [salt-minion] at time 02:51:36.690689
2019-05-25 02:51:36,691 [salt.state       :1813][INFO    ][13246] Executing state service.running for [salt-minion]
2019-05-25 02:51:36,692 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-25 02:51:36,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-25 02:51:36,759 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-25 02:51:36,780 [salt.state       :300 ][INFO    ][13246] The service salt-minion is already running
2019-05-25 02:51:36,780 [salt.state       :1951][INFO    ][13246] Completed state [salt-minion] at time 02:51:36.780661 duration_in_ms=89.972
2019-05-25 02:51:36,784 [salt.state       :1780][INFO    ][13246] Running state [/etc/salt/grains.d] at time 02:51:36.784625
2019-05-25 02:51:36,785 [salt.state       :1813][INFO    ][13246] Executing state file.directory for [/etc/salt/grains.d]
2019-05-25 02:51:36,787 [salt.state       :300 ][INFO    ][13246] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-25 02:51:36,787 [salt.state       :1951][INFO    ][13246] Completed state [/etc/salt/grains.d] at time 02:51:36.787707 duration_in_ms=3.082
2019-05-25 02:51:36,788 [salt.state       :1780][INFO    ][13246] Running state [/etc/salt/grains] at time 02:51:36.788837
2019-05-25 02:51:36,789 [salt.state       :1813][INFO    ][13246] Executing state file.managed for [/etc/salt/grains]
2019-05-25 02:51:36,790 [salt.state       :300 ][INFO    ][13246] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-25 02:51:36,791 [salt.state       :1951][INFO    ][13246] Completed state [/etc/salt/grains] at time 02:51:36.791047 duration_in_ms=2.209
2019-05-25 02:51:36,792 [salt.state       :1780][INFO    ][13246] Running state [/etc/salt/grains.d/placeholder] at time 02:51:36.792217
2019-05-25 02:51:36,792 [salt.state       :1813][INFO    ][13246] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-25 02:51:36,793 [salt.state       :300 ][INFO    ][13246] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-25 02:51:36,795 [salt.state       :1951][INFO    ][13246] Completed state [/etc/salt/grains.d/placeholder] at time 02:51:36.795086 duration_in_ms=2.87
2019-05-25 02:51:36,795 [salt.state       :1780][INFO    ][13246] Running state [/etc/salt/grains.d/sphinx] at time 02:51:36.795894
2019-05-25 02:51:36,796 [salt.state       :1813][INFO    ][13246] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-25 02:51:36,797 [salt.state       :300 ][INFO    ][13246] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-25 02:51:36,798 [salt.state       :1951][INFO    ][13246] Completed state [/etc/salt/grains.d/sphinx] at time 02:51:36.797697 duration_in_ms=1.803
2019-05-25 02:51:36,800 [salt.state       :1780][INFO    ][13246] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:36.800343
2019-05-25 02:51:36,800 [salt.state       :1813][INFO    ][13246] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-25 02:51:36,800 [salt.state       :300 ][INFO    ][13246] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-25 02:51:36,801 [salt.state       :1951][INFO    ][13246] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:36.801181 duration_in_ms=0.838
2019-05-25 02:51:36,801 [salt.state       :1780][INFO    ][13246] Running state [/etc/salt/grains.d/dns_records] at time 02:51:36.801689
2019-05-25 02:51:36,802 [salt.state       :1813][INFO    ][13246] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-25 02:51:36,803 [salt.state       :300 ][INFO    ][13246] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-25 02:51:36,803 [salt.state       :1951][INFO    ][13246] Completed state [/etc/salt/grains.d/dns_records] at time 02:51:36.803246 duration_in_ms=1.557
2019-05-25 02:51:36,804 [salt.state       :1780][INFO    ][13246] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:36.804196
2019-05-25 02:51:36,804 [salt.state       :1813][INFO    ][13246] 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-25 02:51:36,804 [salt.state       :300 ][INFO    ][13246] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-25 02:51:36,805 [salt.state       :1951][INFO    ][13246] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:36.805028 duration_in_ms=0.833
2019-05-25 02:51:36,805 [salt.state       :1780][INFO    ][13246] Running state [/etc/salt/grains.d/salt] at time 02:51:36.805539
2019-05-25 02:51:36,807 [salt.state       :1813][INFO    ][13246] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-25 02:51:36,808 [salt.state       :300 ][INFO    ][13246] File /etc/salt/grains.d/salt is in the correct state
2019-05-25 02:51:36,808 [salt.state       :1951][INFO    ][13246] Completed state [/etc/salt/grains.d/salt] at time 02:51:36.808751 duration_in_ms=3.21
2019-05-25 02:51:36,810 [salt.state       :1780][INFO    ][13246] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:36.809666
2019-05-25 02:51:36,810 [salt.state       :1813][INFO    ][13246] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-25 02:51:36,810 [salt.state       :300 ][INFO    ][13246] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-25 02:51:36,810 [salt.state       :1951][INFO    ][13246] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:36.810844 duration_in_ms=1.179
2019-05-25 02:51:36,812 [salt.state       :1780][INFO    ][13246] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:36.812794
2019-05-25 02:51:36,813 [salt.state       :1813][INFO    ][13246] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-25 02:51:36,813 [salt.state       :300 ][INFO    ][13246] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-25 02:51:36,813 [salt.state       :1951][INFO    ][13246] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:36.813644 duration_in_ms=0.851
2019-05-25 02:51:36,814 [salt.state       :1780][INFO    ][13246] Running state [mine.update] at time 02:51:36.814351
2019-05-25 02:51:36,814 [salt.state       :1813][INFO    ][13246] Executing state module.wait for [mine.update]
2019-05-25 02:51:36,814 [salt.state       :300 ][INFO    ][13246] No changes made for mine.update
2019-05-25 02:51:36,815 [salt.state       :1951][INFO    ][13246] Completed state [mine.update] at time 02:51:36.815130 duration_in_ms=0.779
2019-05-25 02:51:36,815 [salt.state       :1780][INFO    ][13246] Running state [ca-certificates] at time 02:51:36.815409
2019-05-25 02:51:36,815 [salt.state       :1813][INFO    ][13246] Executing state pkg.installed for [ca-certificates]
2019-05-25 02:51:36,826 [salt.state       :300 ][INFO    ][13246] All specified packages are already installed
2019-05-25 02:51:36,826 [salt.state       :1951][INFO    ][13246] Completed state [ca-certificates] at time 02:51:36.826418 duration_in_ms=11.009
2019-05-25 02:51:36,827 [salt.state       :1780][INFO    ][13246] Running state [update-ca-certificates] at time 02:51:36.827146
2019-05-25 02:51:36,827 [salt.state       :1813][INFO    ][13246] Executing state cmd.wait for [update-ca-certificates]
2019-05-25 02:51:36,827 [salt.state       :300 ][INFO    ][13246] No changes made for update-ca-certificates
2019-05-25 02:51:36,828 [salt.state       :1951][INFO    ][13246] Completed state [update-ca-certificates] at time 02:51:36.827955 duration_in_ms=0.809
2019-05-25 02:51:36,828 [salt.state       :1780][INFO    ][13246] Running state [iptables] at time 02:51:36.828226
2019-05-25 02:51:36,828 [salt.state       :1813][INFO    ][13246] Executing state pkg.installed for [iptables]
2019-05-25 02:51:36,838 [salt.state       :300 ][INFO    ][13246] All specified packages are already installed
2019-05-25 02:51:36,839 [salt.state       :1951][INFO    ][13246] Completed state [iptables] at time 02:51:36.839116 duration_in_ms=10.89
2019-05-25 02:51:36,839 [salt.state       :1780][INFO    ][13246] Running state [iptables-persistent] at time 02:51:36.839399
2019-05-25 02:51:36,839 [salt.state       :1813][INFO    ][13246] Executing state pkg.installed for [iptables-persistent]
2019-05-25 02:51:36,849 [salt.state       :300 ][INFO    ][13246] All specified packages are already installed
2019-05-25 02:51:36,849 [salt.state       :1951][INFO    ][13246] Completed state [iptables-persistent] at time 02:51:36.849285 duration_in_ms=9.886
2019-05-25 02:51:36,850 [salt.state       :1780][INFO    ][13246] Running state [iptables_modules_v4_load] at time 02:51:36.850598
2019-05-25 02:51:36,850 [salt.state       :1813][INFO    ][13246] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-25 02:51:36,851 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command 'lsmod' in directory '/root'
2019-05-25 02:51:36,871 [salt.state       :300 ][INFO    ][13246] Kernel modules iptable_filter, ip_tables are already present
2019-05-25 02:51:36,872 [salt.state       :1951][INFO    ][13246] Completed state [iptables_modules_v4_load] at time 02:51:36.872341 duration_in_ms=21.742
2019-05-25 02:51:36,873 [salt.state       :1780][INFO    ][13246] Running state [/etc/iptables/rules.v4] at time 02:51:36.873774
2019-05-25 02:51:36,874 [salt.state       :1813][INFO    ][13246] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-25 02:51:36,977 [salt.state       :300 ][INFO    ][13246] File /etc/iptables/rules.v4 is in the correct state
2019-05-25 02:51:36,978 [salt.state       :1951][INFO    ][13246] Completed state [/etc/iptables/rules.v4] at time 02:51:36.978148 duration_in_ms=104.374
2019-05-25 02:51:36,979 [salt.state       :1780][INFO    ][13246] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:36.979280
2019-05-25 02:51:36,979 [salt.state       :1813][INFO    ][13246] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-25 02:51:36,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-25 02:51:37,000 [salt.state       :300 ][INFO    ][13246] onlyif execution failed
2019-05-25 02:51:37,001 [salt.state       :1951][INFO    ][13246] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:37.001308 duration_in_ms=22.026
2019-05-25 02:51:37,003 [salt.state       :1780][INFO    ][13246] Running state [netfilter-persistent] at time 02:51:37.003485
2019-05-25 02:51:37,004 [salt.state       :1813][INFO    ][13246] Executing state service.running for [netfilter-persistent]
2019-05-25 02:51:37,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-25 02:51:37,031 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-25 02:51:37,057 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-25 02:51:37,080 [salt.state       :300 ][INFO    ][13246] The service netfilter-persistent is already running
2019-05-25 02:51:37,081 [salt.state       :1951][INFO    ][13246] Completed state [netfilter-persistent] at time 02:51:37.081353 duration_in_ms=77.868
2019-05-25 02:51:37,085 [salt.state       :1780][INFO    ][13246] Running state [iptables_extra.remove_stale_tables] at time 02:51:37.085142
2019-05-25 02:51:37,085 [salt.state       :1813][INFO    ][13246] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-25 02:51:37,086 [salt.state       :300 ][INFO    ][13246] No changes made for iptables_extra.remove_stale_tables
2019-05-25 02:51:37,087 [salt.state       :1951][INFO    ][13246] Completed state [iptables_extra.remove_stale_tables] at time 02:51:37.087005 duration_in_ms=1.864
2019-05-25 02:51:37,087 [salt.state       :1780][INFO    ][13246] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:37.087580
2019-05-25 02:51:37,088 [salt.state       :1813][INFO    ][13246] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-25 02:51:37,089 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13246] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-25 02:51:37,109 [salt.state       :300 ][INFO    ][13246] onlyif execution failed
2019-05-25 02:51:37,110 [salt.state       :1951][INFO    ][13246] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:37.110832 duration_in_ms=23.251
2019-05-25 02:51:37,112 [salt.state       :1780][INFO    ][13246] Running state [/etc/iptables/rules.v6] at time 02:51:37.112766
2019-05-25 02:51:37,113 [salt.state       :1813][INFO    ][13246] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-25 02:51:37,114 [salt.state       :300 ][INFO    ][13246] File /etc/iptables/rules.v6 is not present
2019-05-25 02:51:37,115 [salt.state       :1951][INFO    ][13246] Completed state [/etc/iptables/rules.v6] at time 02:51:37.115276 duration_in_ms=2.511
2019-05-25 02:51:37,116 [salt.state       :1780][INFO    ][13246] Running state [iptables_extra.flush_all] at time 02:51:37.116668
2019-05-25 02:51:37,117 [salt.state       :1813][INFO    ][13246] Executing state module.wait for [iptables_extra.flush_all]
2019-05-25 02:51:37,120 [salt.state       :300 ][INFO    ][13246] No changes made for iptables_extra.flush_all
2019-05-25 02:51:37,121 [salt.state       :1951][INFO    ][13246] Completed state [iptables_extra.flush_all] at time 02:51:37.121145 duration_in_ms=4.477
2019-05-25 02:51:37,124 [salt.minion      :1711][INFO    ][13246] Returning information for job: 20190525025124137891
2019-05-25 02:51:37,843 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525025137827712
2019-05-25 02:51:37,869 [salt.minion      :1432][INFO    ][13326] Starting a new job with PID 13326
2019-05-25 02:51:38,991 [salt.state       :915 ][INFO    ][13326] Loading fresh modules for state activity
2019-05-25 02:51:40,219 [salt.state       :1780][INFO    ][13326] Running state [maas-rack-controller] at time 02:51:40.219637
2019-05-25 02:51:40,220 [salt.state       :1813][INFO    ][13326] Executing state pkg.installed for [maas-rack-controller]
2019-05-25 02:51:40,220 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13326] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-25 02:51:40,353 [salt.state       :300 ][INFO    ][13326] All specified packages are already installed
2019-05-25 02:51:40,354 [salt.state       :1951][INFO    ][13326] Completed state [maas-rack-controller] at time 02:51:40.354249 duration_in_ms=134.612
2019-05-25 02:51:40,354 [salt.state       :1780][INFO    ][13326] Running state [ipmitool] at time 02:51:40.354615
2019-05-25 02:51:40,354 [salt.state       :1813][INFO    ][13326] Executing state pkg.installed for [ipmitool]
2019-05-25 02:51:40,364 [salt.state       :300 ][INFO    ][13326] All specified packages are already installed
2019-05-25 02:51:40,365 [salt.state       :1951][INFO    ][13326] Completed state [ipmitool] at time 02:51:40.364987 duration_in_ms=10.372
2019-05-25 02:51:40,368 [salt.state       :1780][INFO    ][13326] Running state [/etc/maas/rackd.conf] at time 02:51:40.368107
2019-05-25 02:51:40,368 [salt.state       :1813][INFO    ][13326] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-25 02:51:40,369 [salt.state       :300 ][INFO    ][13326] No changes needed to be made
2019-05-25 02:51:40,369 [salt.state       :1951][INFO    ][13326] Completed state [/etc/maas/rackd.conf] at time 02:51:40.369590 duration_in_ms=1.483
2019-05-25 02:51:40,370 [salt.state       :1780][INFO    ][13326] Running state [/etc/maas/rackd.conf] at time 02:51:40.370543
2019-05-25 02:51:40,370 [salt.state       :1813][INFO    ][13326] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-25 02:51:40,371 [salt.loaded.int.states.file:2298][WARNING ][13326] 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-25 02:51:40,371 [salt.state       :300 ][INFO    ][13326] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-25 02:51:40,372 [salt.state       :1951][INFO    ][13326] Completed state [/etc/maas/rackd.conf] at time 02:51:40.372191 duration_in_ms=1.648
2019-05-25 02:51:40,373 [salt.state       :1780][INFO    ][13326] Running state [maas-rackd] at time 02:51:40.373221
2019-05-25 02:51:40,373 [salt.state       :1813][INFO    ][13326] Executing state service.running for [maas-rackd]
2019-05-25 02:51:40,374 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13326] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-25 02:51:40,409 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13326] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-25 02:51:40,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13326] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-25 02:51:40,459 [salt.state       :300 ][INFO    ][13326] The service maas-rackd is already running
2019-05-25 02:51:40,459 [salt.state       :1951][INFO    ][13326] Completed state [maas-rackd] at time 02:51:40.459774 duration_in_ms=86.553
2019-05-25 02:51:40,462 [salt.minion      :1711][INFO    ][13326] Returning information for job: 20190525025137827712
2019-05-25 02:51:41,146 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525025141135862
2019-05-25 02:51:41,171 [salt.minion      :1432][INFO    ][13349] Starting a new job with PID 13349
2019-05-25 02:51:42,318 [salt.state       :915 ][INFO    ][13349] Loading fresh modules for state activity
2019-05-25 02:51:43,760 [salt.state       :1780][INFO    ][13349] Running state [maas-region-controller] at time 02:51:43.760233
2019-05-25 02:51:43,760 [salt.state       :1813][INFO    ][13349] Executing state pkg.installed for [maas-region-controller]
2019-05-25 02:51:43,761 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-25 02:51:43,928 [salt.state       :300 ][INFO    ][13349] All specified packages are already installed
2019-05-25 02:51:43,929 [salt.state       :1951][INFO    ][13349] Completed state [maas-region-controller] at time 02:51:43.929249 duration_in_ms=169.016
2019-05-25 02:51:43,929 [salt.state       :1780][INFO    ][13349] Running state [python-oauth] at time 02:51:43.929621
2019-05-25 02:51:43,929 [salt.state       :1813][INFO    ][13349] Executing state pkg.installed for [python-oauth]
2019-05-25 02:51:43,945 [salt.state       :300 ][INFO    ][13349] All specified packages are already installed
2019-05-25 02:51:43,949 [salt.state       :1951][INFO    ][13349] Completed state [python-oauth] at time 02:51:43.949335 duration_in_ms=19.713
2019-05-25 02:51:43,953 [salt.state       :1780][INFO    ][13349] Running state [/etc/maas/regiond.conf] at time 02:51:43.953224
2019-05-25 02:51:43,953 [salt.state       :1813][INFO    ][13349] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-25 02:51:43,960 [salt.state       :300 ][INFO    ][13349] No changes needed to be made
2019-05-25 02:51:43,960 [salt.state       :1951][INFO    ][13349] Completed state [/etc/maas/regiond.conf] at time 02:51:43.960860 duration_in_ms=7.636
2019-05-25 02:51:43,961 [salt.state       :1780][INFO    ][13349] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:43.961371
2019-05-25 02:51:43,961 [salt.state       :1813][INFO    ][13349] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-25 02:51:44,025 [salt.state       :300 ][INFO    ][13349] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-25 02:51:44,026 [salt.state       :1951][INFO    ][13349] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:44.025953 duration_in_ms=64.58
2019-05-25 02:51:44,026 [salt.state       :1780][INFO    ][13349] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:44.026493
2019-05-25 02:51:44,026 [salt.state       :1813][INFO    ][13349] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-25 02:51:44,031 [salt.state       :300 ][INFO    ][13349] No changes needed to be made
2019-05-25 02:51:44,032 [salt.state       :1951][INFO    ][13349] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:44.031980 duration_in_ms=5.487
2019-05-25 02:51:44,032 [salt.state       :1780][INFO    ][13349] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:44.032493
2019-05-25 02:51:44,032 [salt.state       :1813][INFO    ][13349] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-25 02:51:44,036 [salt.state       :300 ][INFO    ][13349] No changes needed to be made
2019-05-25 02:51:44,037 [salt.state       :1951][INFO    ][13349] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:44.037183 duration_in_ms=4.689
2019-05-25 02:51:44,038 [salt.state       :1780][INFO    ][13349] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:44.037694
2019-05-25 02:51:44,038 [salt.state       :1813][INFO    ][13349] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-25 02:51:44,067 [salt.state       :300 ][INFO    ][13349] No changes needed to be made
2019-05-25 02:51:44,067 [salt.state       :1951][INFO    ][13349] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:44.067802 duration_in_ms=30.108
2019-05-25 02:51:44,068 [salt.state       :1780][INFO    ][13349] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:44.068415
2019-05-25 02:51:44,068 [salt.state       :1813][INFO    ][13349] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-25 02:51:44,088 [salt.state       :300 ][INFO    ][13349] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-25 02:51:44,088 [salt.state       :1951][INFO    ][13349] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:44.088446 duration_in_ms=20.03
2019-05-25 02:51:44,091 [salt.state       :1780][INFO    ][13349] Running state [a2enmod headers] at time 02:51:44.091440
2019-05-25 02:51:44,091 [salt.state       :1813][INFO    ][13349] Executing state cmd.run for [a2enmod headers]
2019-05-25 02:51:44,092 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command 'a2enmod headers' in directory '/root'
2019-05-25 02:51:44,158 [salt.state       :300 ][INFO    ][13349] {'pid': 13379, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-25 02:51:44,158 [salt.state       :1951][INFO    ][13349] Completed state [a2enmod headers] at time 02:51:44.158758 duration_in_ms=67.317
2019-05-25 02:51:44,159 [salt.state       :1780][INFO    ][13349] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:44.159237
2019-05-25 02:51:44,159 [salt.state       :1813][INFO    ][13349] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-25 02:51:44,192 [salt.state       :300 ][INFO    ][13349] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-25 02:51:44,192 [salt.state       :1951][INFO    ][13349] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:44.192610 duration_in_ms=33.371
2019-05-25 02:51:44,193 [salt.state       :1780][INFO    ][13349] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:44.193570
2019-05-25 02:51:44,194 [salt.state       :1813][INFO    ][13349] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-25 02:51:44,254 [salt.state       :300 ][INFO    ][13349] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-25 02:51:44,254 [salt.state       :1951][INFO    ][13349] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:44.254537 duration_in_ms=60.967
2019-05-25 02:51:44,255 [salt.state       :1780][INFO    ][13349] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:44.255465
2019-05-25 02:51:44,256 [salt.state       :1813][INFO    ][13349] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-25 02:51:44,324 [salt.state       :300 ][INFO    ][13349] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-25 02:51:44,324 [salt.state       :1951][INFO    ][13349] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:44.324815 duration_in_ms=69.349
2019-05-25 02:51:44,325 [salt.state       :1780][INFO    ][13349] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:44.325337
2019-05-25 02:51:44,325 [salt.state       :1813][INFO    ][13349] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-25 02:51:44,400 [salt.state       :300 ][INFO    ][13349] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-25 02:51:44,401 [salt.state       :1951][INFO    ][13349] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:44.401127 duration_in_ms=75.79
2019-05-25 02:51:44,401 [salt.state       :1780][INFO    ][13349] Running state [/root/.pgpass] at time 02:51:44.401421
2019-05-25 02:51:44,402 [salt.state       :1813][INFO    ][13349] Executing state file.managed for [/root/.pgpass]
2019-05-25 02:51:44,451 [salt.state       :300 ][INFO    ][13349] File /root/.pgpass is in the correct state
2019-05-25 02:51:44,452 [salt.state       :1951][INFO    ][13349] Completed state [/root/.pgpass] at time 02:51:44.451998 duration_in_ms=50.577
2019-05-25 02:51:44,459 [salt.state       :1780][INFO    ][13349] Running state [maas-region syncdb --noinput] at time 02:51:44.459569
2019-05-25 02:51:44,460 [salt.state       :1813][INFO    ][13349] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-25 02:51:44,460 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-25 02:51:47,214 [salt.state       :300 ][INFO    ][13349] {'pid': 13392, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, sessions, piston3, contenttypes, maasserver, metadataserver, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-25 02:51:47,215 [salt.state       :1951][INFO    ][13349] Completed state [maas-region syncdb --noinput] at time 02:51:47.215272 duration_in_ms=2755.703
2019-05-25 02:51:47,215 [salt.state       :2022][WARNING ][13349] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-25 02:51:47,220 [salt.state       :1780][INFO    ][13349] Running state [maas-regiond] at time 02:51:47.220212
2019-05-25 02:51:47,221 [salt.state       :1813][INFO    ][13349] Executing state service.running for [maas-regiond]
2019-05-25 02:51:47,223 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-25 02:51:47,269 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-25 02:51:47,288 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-25 02:51:47,310 [salt.state       :300 ][INFO    ][13349] The service maas-regiond is already running
2019-05-25 02:51:47,311 [salt.state       :1951][INFO    ][13349] Completed state [maas-regiond] at time 02:51:47.310985 duration_in_ms=90.774
2019-05-25 02:51:47,314 [salt.state       :1780][INFO    ][13349] Running state [bind9] at time 02:51:47.314506
2019-05-25 02:51:47,315 [salt.state       :1813][INFO    ][13349] Executing state service.running for [bind9]
2019-05-25 02:51:47,316 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-25 02:51:47,348 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-25 02:51:47,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-25 02:51:47,396 [salt.state       :300 ][INFO    ][13349] The service bind9 is already running
2019-05-25 02:51:47,397 [salt.state       :1951][INFO    ][13349] Completed state [bind9] at time 02:51:47.397552 duration_in_ms=83.04
2019-05-25 02:51:47,399 [salt.state       :1780][INFO    ][13349] Running state [apache2] at time 02:51:47.399797
2019-05-25 02:51:47,400 [salt.state       :1813][INFO    ][13349] Executing state service.running for [apache2]
2019-05-25 02:51:47,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-25 02:51:47,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-25 02:51:47,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-25 02:51:47,483 [salt.state       :300 ][INFO    ][13349] The service apache2 is already running
2019-05-25 02:51:47,484 [salt.state       :1951][INFO    ][13349] Completed state [apache2] at time 02:51:47.484495 duration_in_ms=84.697
2019-05-25 02:51:47,489 [salt.state       :1780][INFO    ][13349] Running state [maasng.wait_for_http_code] at time 02:51:47.489266
2019-05-25 02:51:47,491 [salt.state       :1813][INFO    ][13349] Executing state module.run for [maasng.wait_for_http_code]
2019-05-25 02:51:47,492 [salt.utils.decorators:613 ][WARNING ][13349] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:51:47,600 [salt.state       :300 ][INFO    ][13349] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-25 02:51:47,601 [salt.state       :1951][INFO    ][13349] Completed state [maasng.wait_for_http_code] at time 02:51:47.601220 duration_in_ms=111.955
2019-05-25 02:51:47,603 [salt.state       :1780][INFO    ][13349] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:47.602959
2019-05-25 02:51:47,603 [salt.state       :1813][INFO    ][13349] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-25 02:51:47,604 [salt.state       :300 ][INFO    ][13349] /var/lib/maas/.setup_admin exists
2019-05-25 02:51:47,605 [salt.state       :1951][INFO    ][13349] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:47.605223 duration_in_ms=2.263
2019-05-25 02:51:47,608 [salt.state       :1780][INFO    ][13349] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:47.608439
2019-05-25 02:51:47,608 [salt.state       :1813][INFO    ][13349] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 02:51:47,609 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13349] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 02:51:49,504 [salt.state       :300 ][INFO    ][13349] {'pid': 13413, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 02:51:49,505 [salt.state       :1951][INFO    ][13349] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:49.505362 duration_in_ms=1896.921
2019-05-25 02:51:49,511 [salt.state       :1780][INFO    ][13349] Running state [maas_region_boot_source_resources_mirror] at time 02:51:49.511101
2019-05-25 02:51:49,511 [salt.state       :1813][INFO    ][13349] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-25 02:51:49,609 [salt.state       :300 ][INFO    ][13349] {'changes': {}}
2019-05-25 02:51:49,609 [salt.state       :1951][INFO    ][13349] Completed state [maas_region_boot_source_resources_mirror] at time 02:51:49.609341 duration_in_ms=98.238
2019-05-25 02:51:49,611 [salt.state       :1780][INFO    ][13349] Running state [maasng.boot_resources_import] at time 02:51:49.611131
2019-05-25 02:51:49,611 [salt.state       :1813][INFO    ][13349] Executing state module.run for [maasng.boot_resources_import]
2019-05-25 02:51:49,612 [salt.utils.decorators:613 ][WARNING ][13349] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:51:49,705 [salt.loaded.ext.module.maasng:1600][INFO    ][13349] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-25 02:51:54,766 [salt.loaded.ext.module.maasng:1600][INFO    ][13349] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-25 02:51:56,177 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025156163543
2019-05-25 02:51:56,208 [salt.minion      :1432][INFO    ][13438] Starting a new job with PID 13438
2019-05-25 02:51:56,235 [salt.minion      :1711][INFO    ][13438] Returning information for job: 20190525025156163543
2019-05-25 02:51:59,876 [salt.state       :300 ][INFO    ][13349] {'ret': True}
2019-05-25 02:51:59,877 [salt.state       :1951][INFO    ][13349] Completed state [maasng.boot_resources_import] at time 02:51:59.877134 duration_in_ms=10266.002
2019-05-25 02:51:59,879 [salt.state       :1780][INFO    ][13349] Running state [maas_region_boot_sources_selection_xenial] at time 02:51:59.879034
2019-05-25 02:51:59,879 [salt.state       :1813][INFO    ][13349] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-25 02:52:00,076 [salt.state       :300 ][INFO    ][13349] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-25 02:52:00,076 [salt.state       :1951][INFO    ][13349] Completed state [maas_region_boot_sources_selection_xenial] at time 02:52:00.076811 duration_in_ms=197.777
2019-05-25 02:52:00,078 [salt.state       :1780][INFO    ][13349] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:52:00.078614
2019-05-25 02:52:00,079 [salt.state       :1813][INFO    ][13349] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-25 02:52:00,079 [salt.utils.decorators:613 ][WARNING ][13349] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:52:00,080 [salt.loaded.ext.module.maasng:1771][INFO    ][13349] boot-sources sync initiated for ALL Rack's
2019-05-25 02:52:01,341 [salt.state       :300 ][INFO    ][13349] {'ret': True}
2019-05-25 02:52:01,342 [salt.state       :1951][INFO    ][13349] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:52:01.342313 duration_in_ms=1263.698
2019-05-25 02:52:01,344 [salt.state       :1780][INFO    ][13349] Running state [maas.process_maas_config] at time 02:52:01.344749
2019-05-25 02:52:01,345 [salt.state       :1813][INFO    ][13349] Executing state module.run for [maas.process_maas_config]
2019-05-25 02:52:01,347 [salt.utils.decorators:613 ][WARNING ][13349] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:52:01,348 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=enable_http_proxy value=True
2019-05-25 02:52:01,406 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=upstream_dns value=8.8.8.8
2019-05-25 02:52:01,469 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=commissioning_distro_series value=xenial
2019-05-25 02:52:01,527 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=default_osystem value=ubuntu
2019-05-25 02:52:02,980 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=active_discovery_interval value=600
2019-05-25 02:52:03,032 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=dnssec_validation value=no
2019-05-25 02:52:03,092 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=maas_name value=mas01
2019-05-25 02:52:03,143 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=network_discovery value=enabled
2019-05-25 02:52:03,272 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=enable_third_party_drivers value=True
2019-05-25 02:52:03,319 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=default_storage_layout value=lvm
2019-05-25 02:52:03,371 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=ntp_external_only value=True
2019-05-25 02:52:03,414 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-25 02:52:03,476 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=default_distro_series value=xenial
2019-05-25 02:52:03,558 [salt.loaded.ext.module.maas:92  ][INFO    ][13349] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-25 02:52:03,695 [salt.state       :300 ][INFO    ][13349] {'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-25 02:52:03,695 [salt.state       :1951][INFO    ][13349] Completed state [maas.process_maas_config] at time 02:52:03.695692 duration_in_ms=2350.942
2019-05-25 02:52:03,696 [salt.state       :1780][INFO    ][13349] Running state [pxe_admin] at time 02:52:03.696702
2019-05-25 02:52:03,697 [salt.state       :1813][INFO    ][13349] Executing state maasng.fabric_present for [pxe_admin]
2019-05-25 02:52:03,755 [salt.loaded.ext.module.maasng:945 ][INFO    ][13349] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 02:52:03,831 [salt.loaded.ext.module.maasng:1008][WARNING ][13349] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-25 02:52:03,831 [salt.loaded.ext.module.maasng:1011][WARNING ][13349] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-25 02:52:03,895 [salt.state       :300 ][INFO    ][13349] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-25 02:52:03,895 [salt.state       :1951][INFO    ][13349] Completed state [pxe_admin] at time 02:52:03.895735 duration_in_ms=199.033
2019-05-25 02:52:03,896 [salt.state       :1780][INFO    ][13349] Running state [vlan 0] at time 02:52:03.896251
2019-05-25 02:52:03,896 [salt.state       :1813][INFO    ][13349] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-25 02:52:03,952 [salt.loaded.ext.module.maasng:945 ][INFO    ][13349] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 02:52:04,067 [salt.loaded.ext.module.maasng:945 ][INFO    ][13349] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 02:52:04,334 [salt.loaded.ext.module.maasng:945 ][INFO    ][13349] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 02:52:04,443 [salt.state       :300 ][INFO    ][13349] {'new': 'Vlan untagged was updated'}
2019-05-25 02:52:04,443 [salt.state       :1951][INFO    ][13349] Completed state [vlan 0] at time 02:52:04.443744 duration_in_ms=547.492
2019-05-25 02:52:04,445 [salt.state       :1780][INFO    ][13349] Running state [192.168.11.0/24] at time 02:52:04.445299
2019-05-25 02:52:04,447 [salt.state       :1813][INFO    ][13349] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-25 02:52:04,652 [salt.loaded.ext.module.maasng:945 ][INFO    ][13349] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-05-25 02:52:04,653 [salt.loaded.ext.module.maasng:1235][WARNING ][13349] Ignoring parameter vlan:0
2019-05-25 02:52:04,714 [salt.state       :300 ][INFO    ][13349] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-25 02:52:04,714 [salt.state       :1951][INFO    ][13349] Completed state [192.168.11.0/24] at time 02:52:04.714625 duration_in_ms=269.325
2019-05-25 02:52:04,715 [salt.state       :1780][INFO    ][13349] Running state [maas_create_iprange_1] at time 02:52:04.715628
2019-05-25 02:52:04,716 [salt.state       :1813][INFO    ][13349] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-25 02:52:04,765 [salt.state       :300 ][INFO    ][13349] Iprange maas_create_iprange_1 already exist.
2019-05-25 02:52:04,765 [salt.state       :1951][INFO    ][13349] Completed state [maas_create_iprange_1] at time 02:52:04.765321 duration_in_ms=49.693
2019-05-25 02:52:04,765 [salt.state       :1780][INFO    ][13349] Running state [vlan 0] at time 02:52:04.765645
2019-05-25 02:52:04,767 [salt.state       :1813][INFO    ][13349] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-25 02:52:04,818 [salt.loaded.ext.module.maasng:945 ][INFO    ][13349] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wdc7sr', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 02:52:04,912 [salt.loaded.ext.module.maasng:945 ][INFO    ][13349] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 02:52:05,169 [salt.loaded.ext.module.maasng:945 ][INFO    ][13349] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'wdc7sr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 02:52:05,279 [salt.state       :300 ][INFO    ][13349] {'new': 'Vlan untagged was updated'}
2019-05-25 02:52:05,279 [salt.state       :1951][INFO    ][13349] Completed state [vlan 0] at time 02:52:05.279546 duration_in_ms=513.9
2019-05-25 02:52:05,280 [salt.state       :1780][INFO    ][13349] Running state [opnfv] at time 02:52:05.280327
2019-05-25 02:52:05,280 [salt.state       :1813][INFO    ][13349] Executing state maasng.sshkey_present for [opnfv]
2019-05-25 02:52:05,348 [salt.loaded.ext.module.maasng:1903][INFO    ][13349] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-25 02:52:05,349 [salt.state       :300 ][INFO    ][13349] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-25 02:52:05,349 [salt.state       :1951][INFO    ][13349] Completed state [opnfv] at time 02:52:05.349398 duration_in_ms=69.072
2019-05-25 02:52:05,357 [salt.minion      :1711][INFO    ][13349] Returning information for job: 20190525025141135862
2019-05-25 02:52:06,123 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525025206111496
2019-05-25 02:52:06,143 [salt.minion      :1432][INFO    ][13825] Starting a new job with PID 13825
2019-05-25 02:52:13,935 [salt.state       :915 ][INFO    ][13825] Loading fresh modules for state activity
2019-05-25 02:52:14,052 [salt.state       :1780][INFO    ][13825] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:14.052645
2019-05-25 02:52:14,053 [salt.state       :1813][INFO    ][13825] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 02:52:14,055 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13825] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 02:52:15,903 [salt.state       :300 ][INFO    ][13825] {'pid': 13853, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 02:52:15,904 [salt.state       :1951][INFO    ][13825] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:15.904605 duration_in_ms=1851.959
2019-05-25 02:52:15,908 [salt.state       :1780][INFO    ][13825] Running state [maas.process_machines] at time 02:52:15.908188
2019-05-25 02:52:15,908 [salt.state       :1813][INFO    ][13825] Executing state module.run for [maas.process_machines]
2019-05-25 02:52:15,910 [salt.utils.decorators:613 ][WARNING ][13825] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:52:16,487 [salt.loaded.ext.module.maas:412 ][WARNING ][13825] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 02:52:16,489 [salt.loaded.ext.module.maas:92  ][INFO    ][13825] 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=r6de63 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-25 02:52:17,715 [salt.loaded.ext.module.maas:412 ][WARNING ][13825] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 02:52:17,717 [salt.loaded.ext.module.maas:92  ][INFO    ][13825] 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=4ebewe architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-25 02:52:18,954 [salt.loaded.ext.module.maas:412 ][WARNING ][13825] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 02:52:18,955 [salt.loaded.ext.module.maas:92  ][INFO    ][13825] 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=hwepq4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-25 02:52:20,167 [salt.loaded.ext.module.maas:412 ][WARNING ][13825] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 02:52:20,168 [salt.loaded.ext.module.maas:92  ][INFO    ][13825] 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=hb83gn architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-25 02:52:21,245 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025221232877
2019-05-25 02:52:21,271 [salt.minion      :1432][INFO    ][14024] Starting a new job with PID 14024
2019-05-25 02:52:21,299 [salt.minion      :1711][INFO    ][14024] Returning information for job: 20190525025221232877
2019-05-25 02:52:21,420 [salt.loaded.ext.module.maas:412 ][WARNING ][13825] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 02:52:21,421 [salt.loaded.ext.module.maas:92  ][INFO    ][13825] 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=f7r4ds architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-25 02:52:22,643 [salt.state       :300 ][INFO    ][13825] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-25 02:52:22,643 [salt.state       :1951][INFO    ][13825] Completed state [maas.process_machines] at time 02:52:22.643635 duration_in_ms=6735.446
2019-05-25 02:52:22,648 [salt.minion      :1711][INFO    ][13825] Returning information for job: 20190525025206111496
2019-05-25 02:52:56,575 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525025256558747
2019-05-25 02:52:56,603 [salt.minion      :1432][INFO    ][14094] Starting a new job with PID 14094
2019-05-25 02:53:04,510 [salt.state       :915 ][INFO    ][14094] Loading fresh modules for state activity
2019-05-25 02:53:04,622 [salt.state       :1780][INFO    ][14094] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:04.622511
2019-05-25 02:53:04,622 [salt.state       :1813][INFO    ][14094] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 02:53:04,624 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14094] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 02:53:06,454 [salt.state       :300 ][INFO    ][14094] {'pid': 14135, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 02:53:06,456 [salt.state       :1951][INFO    ][14094] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:06.455954 duration_in_ms=1833.442
2019-05-25 02:53:06,460 [salt.state       :1780][INFO    ][14094] Running state [maas.wait_for_machine_status] at time 02:53:06.460028
2019-05-25 02:53:06,460 [salt.state       :1813][INFO    ][14094] Executing state module.run for [maas.wait_for_machine_status]
2019-05-25 02:53:06,461 [salt.utils.decorators:613 ][WARNING ][14094] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:53:09,508 [salt.state       :300 ][INFO    ][14094] {'ret': True}
2019-05-25 02:53:09,508 [salt.state       :1951][INFO    ][14094] Completed state [maas.wait_for_machine_status] at time 02:53:09.508617 duration_in_ms=3048.589
2019-05-25 02:53:09,512 [salt.minion      :1711][INFO    ][14094] Returning information for job: 20190525025256558747
2019-05-25 02:53:10,211 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525025310196503
2019-05-25 02:53:10,237 [salt.minion      :1432][INFO    ][14150] Starting a new job with PID 14150
2019-05-25 02:53:11,338 [salt.state       :915 ][INFO    ][14150] Loading fresh modules for state activity
2019-05-25 02:53:11,497 [salt.state       :1780][INFO    ][14150] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:11.497085
2019-05-25 02:53:11,498 [salt.state       :1813][INFO    ][14150] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 02:53:11,501 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14150] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 02:53:13,352 [salt.state       :300 ][INFO    ][14150] {'pid': 14157, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 02:53:13,352 [salt.state       :1951][INFO    ][14150] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:13.352891 duration_in_ms=1855.807
2019-05-25 02:53:13,355 [salt.state       :1780][INFO    ][14150] Running state [maas_machines_storage_cmp002_lvm] at time 02:53:13.355423
2019-05-25 02:53:13,355 [salt.state       :1813][INFO    ][14150] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-25 02:53:13,884 [salt.state       :300 ][INFO    ][14150] Machine cmp002 is not in Ready state.
2019-05-25 02:53:13,884 [salt.state       :1951][INFO    ][14150] Completed state [maas_machines_storage_cmp002_lvm] at time 02:53:13.884689 duration_in_ms=529.265
2019-05-25 02:53:13,885 [salt.state       :1780][INFO    ][14150] Running state [maas_machines_storage_cmp001_lvm] at time 02:53:13.885110
2019-05-25 02:53:13,885 [salt.state       :1813][INFO    ][14150] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-25 02:53:14,399 [salt.state       :300 ][INFO    ][14150] Machine cmp001 is not in Ready state.
2019-05-25 02:53:14,400 [salt.state       :1951][INFO    ][14150] Completed state [maas_machines_storage_cmp001_lvm] at time 02:53:14.400222 duration_in_ms=515.112
2019-05-25 02:53:14,405 [salt.minion      :1711][INFO    ][14150] Returning information for job: 20190525025310196503
2019-05-25 02:53:15,084 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525025315071604
2019-05-25 02:53:15,106 [salt.minion      :1432][INFO    ][14172] Starting a new job with PID 14172
2019-05-25 02:53:16,255 [salt.state       :915 ][INFO    ][14172] Loading fresh modules for state activity
2019-05-25 02:53:16,347 [salt.state       :1780][INFO    ][14172] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:16.347347
2019-05-25 02:53:16,347 [salt.state       :1813][INFO    ][14172] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 02:53:16,349 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14172] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 02:53:18,187 [salt.state       :300 ][INFO    ][14172] {'pid': 14179, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 02:53:18,188 [salt.state       :1951][INFO    ][14172] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:18.187964 duration_in_ms=1840.618
2019-05-25 02:53:18,191 [salt.state       :1780][INFO    ][14172] Running state [maas.deploy_machines] at time 02:53:18.190885
2019-05-25 02:53:18,191 [salt.state       :1813][INFO    ][14172] Executing state module.run for [maas.deploy_machines]
2019-05-25 02:53:18,191 [salt.utils.decorators:613 ][WARNING ][14172] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:53:18,763 [salt.loaded.ext.module.maas:684 ][INFO    ][14172] deploymachines hwe_kernel=ga-16.04 system_id=f7r4ds distro_series=xenial
2019-05-25 02:53:21,233 [salt.state       :300 ][INFO    ][14172] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-25 02:53:21,233 [salt.state       :1951][INFO    ][14172] Completed state [maas.deploy_machines] at time 02:53:21.233510 duration_in_ms=3042.624
2019-05-25 02:53:21,236 [salt.minion      :1711][INFO    ][14172] Returning information for job: 20190525025315071604
2019-05-25 02:53:21,992 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command state.apply with jid 20190525025321928265
2019-05-25 02:53:22,015 [salt.minion      :1432][INFO    ][14243] Starting a new job with PID 14243
2019-05-25 02:53:29,236 [salt.state       :915 ][INFO    ][14243] Loading fresh modules for state activity
2019-05-25 02:53:29,348 [salt.state       :1780][INFO    ][14243] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:29.348677
2019-05-25 02:53:29,349 [salt.state       :1813][INFO    ][14243] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 02:53:29,353 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14243] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 02:53:31,215 [salt.state       :300 ][INFO    ][14243] {'pid': 14255, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 02:53:31,216 [salt.state       :1951][INFO    ][14243] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:31.215947 duration_in_ms=1867.273
2019-05-25 02:53:31,219 [salt.state       :1780][INFO    ][14243] Running state [maas.wait_for_machine_status] at time 02:53:31.218872
2019-05-25 02:53:31,219 [salt.state       :1813][INFO    ][14243] Executing state module.run for [maas.wait_for_machine_status]
2019-05-25 02:53:31,220 [salt.utils.decorators:613 ][WARNING ][14243] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 02:53:34,125 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.11998606s left)
2019-05-25 02:53:36,980 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025336969607
2019-05-25 02:53:37,008 [salt.minion      :1432][INFO    ][14271] Starting a new job with PID 14271
2019-05-25 02:53:37,038 [salt.minion      :1711][INFO    ][14271] Returning information for job: 20190525025336969607
2019-05-25 02:54:06,971 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.27481294s left)
2019-05-25 02:54:07,075 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025407061408
2019-05-25 02:54:07,100 [salt.minion      :1432][INFO    ][14329] Starting a new job with PID 14329
2019-05-25 02:54:07,125 [salt.minion      :1711][INFO    ][14329] Returning information for job: 20190525025407061408
2019-05-25 02:54:37,128 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025437120367
2019-05-25 02:54:37,150 [salt.minion      :1432][INFO    ][14348] Starting a new job with PID 14348
2019-05-25 02:54:37,181 [salt.minion      :1711][INFO    ][14348] Returning information for job: 20190525025437120367
2019-05-25 02:54:39,819 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.42651892s left)
2019-05-25 02:55:07,211 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025507196349
2019-05-25 02:55:07,234 [salt.minion      :1432][INFO    ][14418] Starting a new job with PID 14418
2019-05-25 02:55:07,273 [salt.minion      :1711][INFO    ][14418] Returning information for job: 20190525025507196349
2019-05-25 02:55:12,595 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.65039492s left)
2019-05-25 02:55:37,303 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025537291121
2019-05-25 02:55:37,331 [salt.minion      :1432][INFO    ][14442] Starting a new job with PID 14442
2019-05-25 02:55:37,359 [salt.minion      :1711][INFO    ][14442] Returning information for job: 20190525025537291121
2019-05-25 02:55:45,384 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.86139894s left)
2019-05-25 02:56:07,403 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025607385111
2019-05-25 02:56:07,431 [salt.minion      :1432][INFO    ][14504] Starting a new job with PID 14504
2019-05-25 02:56:07,461 [salt.minion      :1711][INFO    ][14504] Returning information for job: 20190525025607385111
2019-05-25 02:56:18,138 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.10710192s left)
2019-05-25 02:56:37,495 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025637479394
2019-05-25 02:56:37,523 [salt.minion      :1432][INFO    ][14525] Starting a new job with PID 14525
2019-05-25 02:56:37,552 [salt.minion      :1711][INFO    ][14525] Returning information for job: 20190525025637479394
2019-05-25 02:56:51,021 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.22401595s left)
2019-05-25 02:57:07,603 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025707588934
2019-05-25 02:57:07,631 [salt.minion      :1432][INFO    ][14581] Starting a new job with PID 14581
2019-05-25 02:57:07,660 [salt.minion      :1711][INFO    ][14581] Returning information for job: 20190525025707588934
2019-05-25 02:57:24,068 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.17756701s left)
2019-05-25 02:57:37,705 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025737697637
2019-05-25 02:57:37,722 [salt.minion      :1432][INFO    ][14629] Starting a new job with PID 14629
2019-05-25 02:57:37,750 [salt.minion      :1711][INFO    ][14629] Returning information for job: 20190525025737697637
2019-05-25 02:57:56,867 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.37815809s left)
2019-05-25 02:58:07,800 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025807783180
2019-05-25 02:58:07,828 [salt.minion      :1432][INFO    ][14715] Starting a new job with PID 14715
2019-05-25 02:58:07,854 [salt.minion      :1711][INFO    ][14715] Returning information for job: 20190525025807783180
2019-05-25 02:58:29,833 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.41259909s left)
2019-05-25 02:58:37,913 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025837898710
2019-05-25 02:58:37,943 [salt.minion      :1432][INFO    ][14742] Starting a new job with PID 14742
2019-05-25 02:58:37,973 [salt.minion      :1711][INFO    ][14742] Returning information for job: 20190525025837898710
2019-05-25 02:59:03,782 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1917.46299696s left)
2019-05-25 02:59:08,068 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025908054152
2019-05-25 02:59:08,096 [salt.minion      :1432][INFO    ][14953] Starting a new job with PID 14953
2019-05-25 02:59:08,134 [salt.minion      :1711][INFO    ][14953] Returning information for job: 20190525025908054152
2019-05-25 02:59:36,663 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1884.58217311s left)
2019-05-25 02:59:38,200 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525025938183362
2019-05-25 02:59:38,229 [salt.minion      :1432][INFO    ][14978] Starting a new job with PID 14978
2019-05-25 02:59:38,260 [salt.minion      :1711][INFO    ][14978] Returning information for job: 20190525025938183362
2019-05-25 03:00:08,360 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525030008343271
2019-05-25 03:00:08,384 [salt.minion      :1432][INFO    ][15113] Starting a new job with PID 15113
2019-05-25 03:00:08,416 [salt.minion      :1711][INFO    ][15113] Returning information for job: 20190525030008343271
2019-05-25 03:00:09,750 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1851.49494791s left)
2019-05-25 03:00:38,504 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525030038494558
2019-05-25 03:00:38,536 [salt.minion      :1432][INFO    ][15131] Starting a new job with PID 15131
2019-05-25 03:00:38,576 [salt.minion      :1711][INFO    ][15131] Returning information for job: 20190525030038494558
2019-05-25 03:00:43,005 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1818.23989511s left)
2019-05-25 03:01:08,687 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525030108664801
2019-05-25 03:01:08,713 [salt.minion      :1432][INFO    ][15253] Starting a new job with PID 15253
2019-05-25 03:01:08,761 [salt.minion      :1711][INFO    ][15253] Returning information for job: 20190525030108664801
2019-05-25 03:01:15,928 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1785.3169961s left)
2019-05-25 03:01:38,863 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525030138846975
2019-05-25 03:01:38,892 [salt.minion      :1432][INFO    ][15278] Starting a new job with PID 15278
2019-05-25 03:01:38,922 [salt.minion      :1711][INFO    ][15278] Returning information for job: 20190525030138846975
2019-05-25 03:01:48,831 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1752.41401696s left)
2019-05-25 03:02:09,020 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525030209005196
2019-05-25 03:02:09,052 [salt.minion      :1432][INFO    ][15385] Starting a new job with PID 15385
2019-05-25 03:02:09,088 [salt.minion      :1711][INFO    ][15385] Returning information for job: 20190525030209005196
2019-05-25 03:02:21,888 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1719.35736895s left)
2019-05-25 03:02:39,215 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525030239198918
2019-05-25 03:02:39,248 [salt.minion      :1432][INFO    ][15410] Starting a new job with PID 15410
2019-05-25 03:02:39,281 [salt.minion      :1711][INFO    ][15410] Returning information for job: 20190525030239198918
2019-05-25 03:02:54,911 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1686.33483195s left)
2019-05-25 03:03:09,388 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525030309372417
2019-05-25 03:03:09,404 [salt.minion      :1432][INFO    ][15466] Starting a new job with PID 15466
2019-05-25 03:03:09,432 [salt.minion      :1711][INFO    ][15466] Returning information for job: 20190525030309372417
2019-05-25 03:03:27,783 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1653.4620049s left)
2019-05-25 03:03:39,560 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525030339543520
2019-05-25 03:03:39,587 [salt.minion      :1432][INFO    ][15487] Starting a new job with PID 15487
2019-05-25 03:03:39,615 [salt.minion      :1711][INFO    ][15487] Returning information for job: 20190525030339543520
2019-05-25 03:04:00,660 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1620.58581209s left)
2019-05-25 03:04:09,742 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525030409735062
2019-05-25 03:04:09,771 [salt.minion      :1432][INFO    ][15544] Starting a new job with PID 15544
2019-05-25 03:04:09,802 [salt.minion      :1711][INFO    ][15544] Returning information for job: 20190525030409735062
2019-05-25 03:04:33,544 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1587.70092607s left)
2019-05-25 03:04:39,924 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525030439911155
2019-05-25 03:04:39,951 [salt.minion      :1432][INFO    ][15565] Starting a new job with PID 15565
2019-05-25 03:04:39,982 [salt.minion      :1711][INFO    ][15565] Returning information for job: 20190525030439911155
2019-05-25 03:05:06,814 [salt.loaded.ext.module.maas:1023][INFO    ][14243] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1554.43126392s left)
2019-05-25 03:05:10,133 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525030510123070
2019-05-25 03:05:10,161 [salt.minion      :1432][INFO    ][15784] Starting a new job with PID 15784
2019-05-25 03:05:10,195 [salt.minion      :1711][INFO    ][15784] Returning information for job: 20190525030510123070
2019-05-25 03:05:40,007 [salt.state       :300 ][INFO    ][14243] {'ret': True}
2019-05-25 03:05:40,008 [salt.state       :1951][INFO    ][14243] Completed state [maas.wait_for_machine_status] at time 03:05:40.008267 duration_in_ms=728789.392
2019-05-25 03:05:40,015 [salt.minion      :1711][INFO    ][14243] Returning information for job: 20190525025321928265
2019-05-25 03:06:11,425 [salt.utils.schedule:1377][INFO    ][5616] Running scheduled job: __mine_interval
2019-05-25 04:06:11,425 [salt.utils.schedule:1377][INFO    ][5616] Running scheduled job: __mine_interval
2019-05-25 04:35:14,804 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command cp.push_dir with jid 20190525043514789334
2019-05-25 04:35:14,829 [salt.minion      :1432][INFO    ][22006] Starting a new job with PID 22006
2019-05-25 04:35:17,155 [salt.minion      :1711][INFO    ][22006] Returning information for job: 20190525043514789334
2019-05-25 04:44:17,671 [salt.minion      :1308][INFO    ][5616] User sudo_ubuntu Executing command cp.push_dir with jid 20190525044417660874
2019-05-25 04:44:17,695 [salt.minion      :1432][INFO    ][22549] Starting a new job with PID 22549
