2019-03-19 02:14:36,129 [salt.utils.decorators:613 ][WARNING ][2337] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:14:36,693 [salt.utils.decorators:613 ][WARNING ][2337] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:14:38,753 [salt.loaded.int.states.file:2298][WARNING ][2509] 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-03-19 02:14:56,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3060] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-19 02:14:56,249 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3060] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-19 02:14:56,266 [salt.utils.parsers:1051][WARNING ][362] Minion received a SIGTERM. Exiting.
2019-03-19 02:14:57,247 [salt.cli.daemons :293 ][INFO    ][3179] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-03-19 02:14:57,355 [salt.cli.daemons :82  ][INFO    ][3179] Starting up the Salt Minion
2019-03-19 02:14:57,355 [salt.utils.event :1017][INFO    ][3179] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-03-19 02:14:58,232 [salt.minion      :976 ][INFO    ][3179] Creating minion process manager
2019-03-19 02:14:59,598 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3179] Executing command ['date', '+%z'] in directory '/root'
2019-03-19 02:15:00,276 [salt.utils.schedule:568 ][INFO    ][3179] Updating job settings for scheduled job: __mine_interval
2019-03-19 02:15:00,281 [salt.minion      :1108][INFO    ][3179] Added mine.update to scheduler
2019-03-19 02:15:00,294 [salt.minion      :1975][INFO    ][3179] Minion is starting as user 'root'
2019-03-19 02:15:00,312 [salt.minion      :2336][INFO    ][3179] Minion is ready to receive requests!
2019-03-19 02:15:02,839 [salt.state       :2022][WARNING ][3066] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-19 02:15:05,309 [salt.utils.decorators:613 ][WARNING ][3066] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:15:09,966 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319021509954125
2019-03-19 02:15:09,989 [salt.minion      :1432][INFO    ][3620] Starting a new job with PID 3620
2019-03-19 02:15:10,010 [salt.minion      :1711][INFO    ][3620] Returning information for job: 20190319021509954125
2019-03-19 02:15:40,010 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319021539998250
2019-03-19 02:15:40,033 [salt.minion      :1432][INFO    ][3812] Starting a new job with PID 3812
2019-03-19 02:15:40,055 [salt.minion      :1711][INFO    ][3812] Returning information for job: 20190319021539998250
2019-03-19 02:16:10,068 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319021610058626
2019-03-19 02:16:10,090 [salt.minion      :1432][INFO    ][4005] Starting a new job with PID 4005
2019-03-19 02:16:10,105 [salt.minion      :1711][INFO    ][4005] Returning information for job: 20190319021610058626
2019-03-19 02:16:40,127 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319021640111246
2019-03-19 02:16:40,152 [salt.minion      :1432][INFO    ][4262] Starting a new job with PID 4262
2019-03-19 02:16:40,173 [salt.minion      :1711][INFO    ][4262] Returning information for job: 20190319021640111246
2019-03-19 02:16:47,850 [salt.utils.decorators:613 ][WARNING ][3066] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:17:10,179 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319021710167665
2019-03-19 02:17:10,200 [salt.minion      :1432][INFO    ][4540] Starting a new job with PID 4540
2019-03-19 02:17:10,216 [salt.minion      :1711][INFO    ][4540] Returning information for job: 20190319021710167665
2019-03-19 02:17:21,275 [salt.utils.decorators:613 ][WARNING ][3066] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:17:22,287 [salt.utils.decorators:613 ][WARNING ][3066] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:17:26,548 [salt.loaded.ext.module.maasng:1008][WARNING ][3066] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-03-19 02:17:26,549 [salt.loaded.ext.module.maasng:1011][WARNING ][3066] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-03-19 02:17:27,459 [salt.loaded.ext.module.maasng:1235][WARNING ][3066] Ignoring parameter vlan:0
2019-03-19 02:17:29,284 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319021729271578
2019-03-19 02:17:29,300 [salt.minion      :1432][INFO    ][4926] Starting a new job with PID 4926
2019-03-19 02:17:32,880 [salt.state       :915 ][INFO    ][4926] Loading fresh modules for state activity
2019-03-19 02:17:32,928 [salt.fileclient  :1219][INFO    ][4926] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-03-19 02:17:32,958 [salt.state       :1780][INFO    ][4926] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:17:32.957995
2019-03-19 02:17:32,958 [salt.state       :1813][INFO    ][4926] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-19 02:17:32,959 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4926] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-19 02:17:34,407 [salt.state       :300 ][INFO    ][4926] {'pid': 4951, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-19 02:17:34,407 [salt.state       :1951][INFO    ][4926] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:17:34.407712 duration_in_ms=1449.717
2019-03-19 02:17:34,408 [salt.state       :1780][INFO    ][4926] Running state [maas.process_machines] at time 02:17:34.408934
2019-03-19 02:17:34,409 [salt.state       :1813][INFO    ][4926] Executing state module.run for [maas.process_machines]
2019-03-19 02:17:34,409 [salt.utils.decorators:613 ][WARNING ][4926] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:17:34,485 [salt.loaded.ext.module.maas:412 ][WARNING ][4926] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-19 02:17:34,485 [salt.loaded.ext.module.maas:92  ][INFO    ][4926] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-03-19 02:17:36,023 [salt.loaded.ext.module.maas:412 ][WARNING ][4926] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-19 02:17:36,024 [salt.loaded.ext.module.maas:92  ][INFO    ][4926] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-03-19 02:17:37,454 [salt.loaded.ext.module.maas:412 ][WARNING ][4926] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-19 02:17:37,455 [salt.loaded.ext.module.maas:92  ][INFO    ][4926] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-03-19 02:17:38,880 [salt.loaded.ext.module.maas:412 ][WARNING ][4926] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-19 02:17:38,881 [salt.loaded.ext.module.maas:92  ][INFO    ][4926] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-03-19 02:17:40,190 [salt.loaded.ext.module.maas:412 ][WARNING ][4926] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-19 02:17:40,191 [salt.loaded.ext.module.maas:92  ][INFO    ][4926] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-03-19 02:17:42,126 [salt.state       :300 ][INFO    ][4926] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-19 02:17:42,126 [salt.state       :1951][INFO    ][4926] Completed state [maas.process_machines] at time 02:17:42.126682 duration_in_ms=7717.746
2019-03-19 02:17:42,130 [salt.minion      :1711][INFO    ][4926] Returning information for job: 20190319021729271578
2019-03-19 02:18:13,295 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319021813281031
2019-03-19 02:18:13,319 [salt.minion      :1432][INFO    ][5265] Starting a new job with PID 5265
2019-03-19 02:18:17,002 [salt.state       :915 ][INFO    ][5265] Loading fresh modules for state activity
2019-03-19 02:18:17,059 [salt.fileclient  :1219][INFO    ][5265] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-19 02:18:17,101 [salt.state       :1780][INFO    ][5265] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:18:17.101258
2019-03-19 02:18:17,101 [salt.state       :1813][INFO    ][5265] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-19 02:18:17,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5265] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-19 02:18:18,382 [salt.state       :300 ][INFO    ][5265] {'pid': 5272, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-19 02:18:18,382 [salt.state       :1951][INFO    ][5265] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:18:18.382496 duration_in_ms=1281.239
2019-03-19 02:18:18,383 [salt.state       :1780][INFO    ][5265] Running state [maas.wait_for_machine_status] at time 02:18:18.383555
2019-03-19 02:18:18,383 [salt.state       :1813][INFO    ][5265] Executing state module.run for [maas.wait_for_machine_status]
2019-03-19 02:18:18,384 [salt.utils.decorators:613 ][WARNING ][5265] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:18:19,431 [salt.loaded.ext.module.maas:1023][INFO    ][5265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.95666313s left)
2019-03-19 02:18:28,399 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319021828386706
2019-03-19 02:18:28,423 [salt.minion      :1432][INFO    ][5302] Starting a new job with PID 5302
2019-03-19 02:18:28,444 [salt.minion      :1711][INFO    ][5302] Returning information for job: 20190319021828386706
2019-03-19 02:18:50,398 [salt.loaded.ext.module.maas:1023][INFO    ][5265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.98954296s left)
2019-03-19 02:18:58,448 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319021858435885
2019-03-19 02:18:58,473 [salt.minion      :1432][INFO    ][5331] Starting a new job with PID 5331
2019-03-19 02:18:58,496 [salt.minion      :1711][INFO    ][5331] Returning information for job: 20190319021858435885
2019-03-19 02:19:21,336 [salt.loaded.ext.module.maas:1023][INFO    ][5265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.05162501s left)
2019-03-19 02:19:28,513 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319021928500293
2019-03-19 02:19:28,536 [salt.minion      :1432][INFO    ][5490] Starting a new job with PID 5490
2019-03-19 02:19:28,556 [salt.minion      :1711][INFO    ][5490] Returning information for job: 20190319021928500293
2019-03-19 02:19:52,736 [salt.loaded.ext.module.maas:1023][INFO    ][5265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.65190315s left)
2019-03-19 02:19:58,560 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319021958548100
2019-03-19 02:19:58,585 [salt.minion      :1432][INFO    ][5609] Starting a new job with PID 5609
2019-03-19 02:19:58,606 [salt.minion      :1711][INFO    ][5609] Returning information for job: 20190319021958548100
2019-03-19 02:20:23,839 [salt.loaded.ext.module.maas:1023][INFO    ][5265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.54831314s left)
2019-03-19 02:20:28,623 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022028610760
2019-03-19 02:20:28,649 [salt.minion      :1432][INFO    ][6109] Starting a new job with PID 6109
2019-03-19 02:20:28,671 [salt.minion      :1711][INFO    ][6109] Returning information for job: 20190319022028610760
2019-03-19 02:20:55,599 [salt.loaded.ext.module.maas:1023][INFO    ][5265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.78890014s left)
2019-03-19 02:20:58,690 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022058673774
2019-03-19 02:20:58,714 [salt.minion      :1432][INFO    ][6215] Starting a new job with PID 6215
2019-03-19 02:20:58,736 [salt.minion      :1711][INFO    ][6215] Returning information for job: 20190319022058673774
2019-03-19 02:21:27,436 [salt.loaded.ext.module.maas:1023][INFO    ][5265] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1310.95215011s left)
2019-03-19 02:21:28,748 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022128735847
2019-03-19 02:21:28,771 [salt.minion      :1432][INFO    ][6441] Starting a new job with PID 6441
2019-03-19 02:21:28,793 [salt.minion      :1711][INFO    ][6441] Returning information for job: 20190319022128735847
2019-03-19 02:21:58,771 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022158753498
2019-03-19 02:21:58,795 [salt.minion      :1432][INFO    ][6693] Starting a new job with PID 6693
2019-03-19 02:21:58,816 [salt.minion      :1711][INFO    ][6693] Returning information for job: 20190319022158753498
2019-03-19 02:22:00,788 [salt.state       :300 ][INFO    ][5265] {'ret': True}
2019-03-19 02:22:00,788 [salt.state       :1951][INFO    ][5265] Completed state [maas.wait_for_machine_status] at time 02:22:00.788769 duration_in_ms=222405.212
2019-03-19 02:22:00,793 [salt.minion      :1711][INFO    ][5265] Returning information for job: 20190319021813281031
2019-03-19 02:22:01,431 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319022201415656
2019-03-19 02:22:01,454 [salt.minion      :1432][INFO    ][6712] Starting a new job with PID 6712
2019-03-19 02:22:04,916 [salt.state       :915 ][INFO    ][6712] Loading fresh modules for state activity
2019-03-19 02:22:04,943 [salt.fileclient  :1219][INFO    ][6712] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-19 02:22:05,009 [salt.state       :1780][INFO    ][6712] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:22:05.009701
2019-03-19 02:22:05,010 [salt.state       :1813][INFO    ][6712] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-19 02:22:05,012 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6712] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-19 02:22:06,333 [salt.state       :300 ][INFO    ][6712] {'pid': 6727, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-19 02:22:06,334 [salt.state       :1951][INFO    ][6712] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:22:06.334158 duration_in_ms=1324.457
2019-03-19 02:22:06,337 [salt.state       :1780][INFO    ][6712] Running state [maas_machines_storage_cmp002_lvm] at time 02:22:06.337299
2019-03-19 02:22:06,337 [salt.state       :1813][INFO    ][6712] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-19 02:22:07,455 [salt.loaded.ext.module.maasng:610 ][INFO    ][6712] pyhm6g
2019-03-19 02:22:07,455 [salt.loaded.ext.module.maasng:626 ][INFO    ][6712] sda
2019-03-19 02:22:08,134 [salt.loaded.ext.module.maasng:361 ][INFO    ][6712] pyhm6g
2019-03-19 02:22:08,254 [salt.loaded.ext.module.maasng:367 ][INFO    ][6712] [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'pyhm6g', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'55cdc58d-f143-47da-b7a6-8b7e8c5e3742', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pyhm6g', u'device_id': 2, u'filesystem': {u'uuid': u'cf75e400-71f4-4b9e-a482-a4c91f839b16', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 2397992648704}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/blockdevices/2/'}, {u'size': 2397988454400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'id': 7, u'filesystem': {u'uuid': u'b9a97d82-a253-4324-a9d1-9040906621e8', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'6103c641-c357-48e2-a2ca-ba85db73c4a2', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pyhm6g', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/blockdevices/7/'}]
2019-03-19 02:22:08,254 [salt.loaded.ext.module.maasng:632 ][INFO    ][6712] vgroot
2019-03-19 02:22:08,255 [salt.loaded.ext.module.maasng:635 ][INFO    ][6712] lvroot
2019-03-19 02:22:08,255 [salt.loaded.ext.module.maasng:639 ][INFO    ][6712] 107374182400
2019-03-19 02:22:08,996 [salt.loaded.ext.module.maasng:645 ][INFO    ][6712] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/pyhm6g/', u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'pyhm6g', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'uuid': u'8e95fa4d-6a2d-41c1-90bc-612abf82b98c', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/blockdevices/11/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'pyhm6g', u'partition_table_type': None, u'filesystem': {u'uuid': u'1a899354-dc93-408d-8390-6b60c4c3b6db', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 2, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pyhm6g', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'236fe4ca-cd1f-4c94-b26b-f963aba170ce', u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pyhm6g', u'filesystem': {u'uuid': u'd9b64a20-06e3-477b-a672-a1573d570a85', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'uuid': u'8e95fa4d-6a2d-41c1-90bc-612abf82b98c', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/blockdevices/11/', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'pyhm6g', u'partition_table_type': None, u'filesystem': {u'uuid': u'1a899354-dc93-408d-8390-6b60c4c3b6db', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pyhm6g', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 2, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pyhm6g', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'236fe4ca-cd1f-4c94-b26b-f963aba170ce', u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pyhm6g', u'filesystem': {u'uuid': u'd9b64a20-06e3-477b-a672-a1573d570a85', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 2, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pyhm6g', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'236fe4ca-cd1f-4c94-b26b-f963aba170ce', u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pyhm6g', u'filesystem': {u'uuid': u'd9b64a20-06e3-477b-a672-a1573d570a85', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'psqknd', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 20}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'psqknd', 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'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'psqknd', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'pyhm6g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/interfaces/4/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'psqknd', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 20}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'psqknd', 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'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'psqknd', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'pyhm6g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/interfaces/4/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'system_id': u'pyhm6g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/interfaces/12/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'system_id': u'pyhm6g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/interfaces/13/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'system_id': u'pyhm6g', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pyhm6g/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-03-19 02:22:08,998 [salt.state       :300 ][INFO    ][6712] {'new': {'storage_layout': 'lvm'}}
2019-03-19 02:22:08,999 [salt.state       :1951][INFO    ][6712] Completed state [maas_machines_storage_cmp002_lvm] at time 02:22:08.999175 duration_in_ms=2661.875
2019-03-19 02:22:08,999 [salt.state       :1780][INFO    ][6712] Running state [maas_machines_storage_cmp001_lvm] at time 02:22:08.999724
2019-03-19 02:22:09,000 [salt.state       :1813][INFO    ][6712] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-19 02:22:10,411 [salt.loaded.ext.module.maasng:610 ][INFO    ][6712] pkdbey
2019-03-19 02:22:10,411 [salt.loaded.ext.module.maasng:626 ][INFO    ][6712] sda
2019-03-19 02:22:11,027 [salt.loaded.ext.module.maasng:361 ][INFO    ][6712] pkdbey
2019-03-19 02:22:11,148 [salt.loaded.ext.module.maasng:367 ][INFO    ][6712] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 4, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/blockdevices/4/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pkdbey', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'8570048a-06cc-4309-9465-09c0adf0b627', u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/blockdevices/4/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pkdbey', u'filesystem': {u'uuid': u'0074424f-c939-43c6-a612-ddd0514822b0', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 4, u'size': 2397992648704}]}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'id': 9, u'uuid': u'4a962065-0269-4307-b037-4b3bb126f7ea', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/blockdevices/9/', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'pkdbey', u'partition_table_type': None, u'filesystem': {u'uuid': u'82d15d71-952e-4785-854b-cbffc672f892', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 2397988454400, u'type': u'virtual', u'serial': None, u'partitions': []}]
2019-03-19 02:22:11,148 [salt.loaded.ext.module.maasng:632 ][INFO    ][6712] vgroot
2019-03-19 02:22:11,149 [salt.loaded.ext.module.maasng:635 ][INFO    ][6712] lvroot
2019-03-19 02:22:11,149 [salt.loaded.ext.module.maasng:639 ][INFO    ][6712] 107374182400
2019-03-19 02:22:11,742 [salt.loaded.ext.module.maasng:645 ][INFO    ][6712] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38'], 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'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'psqknd', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 30, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'psqknd', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'psqknd', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'pkdbey', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/interfaces/5/'}, u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/blockdevices/4/', u'id': 4, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'used_size': 2397998940160, u'system_id': u'pkdbey', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'6dce750a-9a7b-4fc9-90ae-bdbd2d80f5bc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pkdbey', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bc453fab-f079-4e62-9cf9-cf6347c7bd36', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/blockdevices/4/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'fqdn': u'cmp001.maas', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/pkdbey/', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'pkdbey', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'1e7f38be-f9a8-49ef-8f25-56221693019d', u'name': u'vgroot-lvroot', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/blockdevices/12/', u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'used_size': 107374182400, u'system_id': u'pkdbey', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0ed799cc-dc2e-4a71-b732-469f5d4c1a1e', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/blockdevices/4/', u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'used_size': 2397998940160, u'system_id': u'pkdbey', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'6dce750a-9a7b-4fc9-90ae-bdbd2d80f5bc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pkdbey', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bc453fab-f079-4e62-9cf9-cf6347c7bd36', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/blockdevices/4/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'type': u'physical', u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'uuid': u'1e7f38be-f9a8-49ef-8f25-56221693019d', u'name': u'vgroot-lvroot', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/blockdevices/12/', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'used_size': 107374182400, u'system_id': u'pkdbey', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0ed799cc-dc2e-4a71-b732-469f5d4c1a1e', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pkdbey', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/blockdevices/4/', u'id': 4, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'used_size': 2397998940160, u'system_id': u'pkdbey', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'6dce750a-9a7b-4fc9-90ae-bdbd2d80f5bc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pkdbey', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bc453fab-f079-4e62-9cf9-cf6347c7bd36', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/blockdevices/4/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'psqknd', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 30, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'psqknd', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'psqknd', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'pkdbey', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'effective_mtu': 1500, u'id': 18, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'pkdbey', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/interfaces/18/'}, {u'name': u'enp9s0', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'pkdbey', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/interfaces/19/'}, {u'name': u'enp8s0', u'links': [{u'id': 33, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'pkdbey', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pkdbey/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-03-19 02:22:11,745 [salt.state       :300 ][INFO    ][6712] {'new': {'storage_layout': 'lvm'}}
2019-03-19 02:22:11,745 [salt.state       :1951][INFO    ][6712] Completed state [maas_machines_storage_cmp001_lvm] at time 02:22:11.745293 duration_in_ms=2745.568
2019-03-19 02:22:11,780 [salt.minion      :1711][INFO    ][6712] Returning information for job: 20190319022201415656
2019-03-19 02:22:12,416 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319022212399904
2019-03-19 02:22:12,440 [salt.minion      :1432][INFO    ][6798] Starting a new job with PID 6798
2019-03-19 02:22:13,072 [salt.state       :915 ][INFO    ][6798] Loading fresh modules for state activity
2019-03-19 02:22:13,125 [salt.fileclient  :1219][INFO    ][6798] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-19 02:22:13,166 [salt.state       :1780][INFO    ][6798] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:22:13.166496
2019-03-19 02:22:13,167 [salt.state       :1813][INFO    ][6798] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-19 02:22:13,169 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6798] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-19 02:22:14,623 [salt.state       :300 ][INFO    ][6798] {'pid': 6805, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-19 02:22:14,624 [salt.state       :1951][INFO    ][6798] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:22:14.624365 duration_in_ms=1457.87
2019-03-19 02:22:14,625 [salt.state       :1780][INFO    ][6798] Running state [maas.deploy_machines] at time 02:22:14.625560
2019-03-19 02:22:14,625 [salt.state       :1813][INFO    ][6798] Executing state module.run for [maas.deploy_machines]
2019-03-19 02:22:14,626 [salt.utils.decorators:613 ][WARNING ][6798] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:22:15,370 [salt.loaded.ext.module.maas:684 ][INFO    ][6798] deploymachines hwe_kernel=ga-16.04 system_id=pyhm6g distro_series=xenial
2019-03-19 02:22:18,129 [salt.loaded.ext.module.maas:684 ][INFO    ][6798] deploymachines hwe_kernel=ga-16.04 system_id=pkdbey distro_series=xenial
2019-03-19 02:22:20,768 [salt.loaded.ext.module.maas:684 ][INFO    ][6798] deploymachines hwe_kernel=ga-16.04 system_id=ecggd3 distro_series=xenial
2019-03-19 02:22:23,304 [salt.loaded.ext.module.maas:684 ][INFO    ][6798] deploymachines hwe_kernel=ga-16.04 system_id=qyxqm6 distro_series=xenial
2019-03-19 02:22:25,810 [salt.loaded.ext.module.maas:684 ][INFO    ][6798] deploymachines hwe_kernel=ga-16.04 system_id=hpsmxe distro_series=xenial
2019-03-19 02:22:27,461 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022227448424
2019-03-19 02:22:27,486 [salt.minion      :1432][INFO    ][7095] Starting a new job with PID 7095
2019-03-19 02:22:27,508 [salt.minion      :1711][INFO    ][7095] Returning information for job: 20190319022227448424
2019-03-19 02:22:28,528 [salt.state       :300 ][INFO    ][6798] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-19 02:22:28,529 [salt.state       :1951][INFO    ][6798] Completed state [maas.deploy_machines] at time 02:22:28.529211 duration_in_ms=13903.649
2019-03-19 02:22:28,533 [salt.minion      :1711][INFO    ][6798] Returning information for job: 20190319022212399904
2019-03-19 02:22:29,157 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319022229145125
2019-03-19 02:22:29,182 [salt.minion      :1432][INFO    ][7112] Starting a new job with PID 7112
2019-03-19 02:22:32,520 [salt.state       :915 ][INFO    ][7112] Loading fresh modules for state activity
2019-03-19 02:22:32,571 [salt.fileclient  :1219][INFO    ][7112] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-19 02:22:32,612 [salt.state       :1780][INFO    ][7112] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:22:32.612926
2019-03-19 02:22:32,613 [salt.state       :1813][INFO    ][7112] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-19 02:22:32,615 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7112] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-19 02:22:34,027 [salt.state       :300 ][INFO    ][7112] {'pid': 7123, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-19 02:22:34,027 [salt.state       :1951][INFO    ][7112] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:22:34.027885 duration_in_ms=1414.958
2019-03-19 02:22:34,030 [salt.state       :1780][INFO    ][7112] Running state [maas.wait_for_machine_status] at time 02:22:34.030067
2019-03-19 02:22:34,030 [salt.state       :1813][INFO    ][7112] Executing state module.run for [maas.wait_for_machine_status]
2019-03-19 02:22:34,031 [salt.utils.decorators:613 ][WARNING ][7112] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:22:37,046 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.99430203s left)
2019-03-19 02:22:44,247 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022244234685
2019-03-19 02:22:44,271 [salt.minion      :1432][INFO    ][7141] Starting a new job with PID 7141
2019-03-19 02:22:44,291 [salt.minion      :1711][INFO    ][7141] Returning information for job: 20190319022244234685
2019-03-19 02:23:10,704 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.33601999s left)
2019-03-19 02:23:14,300 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022314286613
2019-03-19 02:23:14,326 [salt.minion      :1432][INFO    ][7179] Starting a new job with PID 7179
2019-03-19 02:23:14,350 [salt.minion      :1711][INFO    ][7179] Returning information for job: 20190319022314286613
2019-03-19 02:23:43,908 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.13180208s left)
2019-03-19 02:23:44,387 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022344375706
2019-03-19 02:23:44,411 [salt.minion      :1432][INFO    ][7212] Starting a new job with PID 7212
2019-03-19 02:23:44,432 [salt.minion      :1711][INFO    ][7212] Returning information for job: 20190319022344375706
2019-03-19 02:24:14,435 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022414422421
2019-03-19 02:24:14,459 [salt.minion      :1432][INFO    ][7339] Starting a new job with PID 7339
2019-03-19 02:24:14,481 [salt.minion      :1711][INFO    ][7339] Returning information for job: 20190319022414422421
2019-03-19 02:24:17,266 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.77403593s left)
2019-03-19 02:24:44,485 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022444472904
2019-03-19 02:24:44,510 [salt.minion      :1432][INFO    ][7511] Starting a new job with PID 7511
2019-03-19 02:24:44,532 [salt.minion      :1711][INFO    ][7511] Returning information for job: 20190319022444472904
2019-03-19 02:24:50,906 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.13386393s left)
2019-03-19 02:25:14,544 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022514529982
2019-03-19 02:25:14,568 [salt.minion      :1432][INFO    ][7921] Starting a new job with PID 7921
2019-03-19 02:25:14,590 [salt.minion      :1711][INFO    ][7921] Returning information for job: 20190319022514529982
2019-03-19 02:25:24,545 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.49518204s left)
2019-03-19 02:25:44,603 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022544589115
2019-03-19 02:25:44,625 [salt.minion      :1432][INFO    ][8116] Starting a new job with PID 8116
2019-03-19 02:25:44,644 [salt.minion      :1711][INFO    ][8116] Returning information for job: 20190319022544589115
2019-03-19 02:25:57,586 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.4536159s left)
2019-03-19 02:26:14,654 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022614642316
2019-03-19 02:26:14,679 [salt.minion      :1432][INFO    ][8434] Starting a new job with PID 8434
2019-03-19 02:26:14,698 [salt.minion      :1711][INFO    ][8434] Returning information for job: 20190319022614642316
2019-03-19 02:26:30,708 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.33219004s left)
2019-03-19 02:26:44,729 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022644711563
2019-03-19 02:26:44,753 [salt.minion      :1432][INFO    ][8507] Starting a new job with PID 8507
2019-03-19 02:26:44,775 [salt.minion      :1711][INFO    ][8507] Returning information for job: 20190319022644711563
2019-03-19 02:27:04,137 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.90309191s left)
2019-03-19 02:27:14,794 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022714781789
2019-03-19 02:27:14,818 [salt.minion      :1432][INFO    ][8560] Starting a new job with PID 8560
2019-03-19 02:27:14,839 [salt.minion      :1711][INFO    ][8560] Returning information for job: 20190319022714781789
2019-03-19 02:27:37,939 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.1003921s left)
2019-03-19 02:27:44,870 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022744858467
2019-03-19 02:27:44,896 [salt.minion      :1432][INFO    ][8610] Starting a new job with PID 8610
2019-03-19 02:27:44,920 [salt.minion      :1711][INFO    ][8610] Returning information for job: 20190319022744858467
2019-03-19 02:28:11,286 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.7535181s left)
2019-03-19 02:28:14,954 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022814942605
2019-03-19 02:28:14,978 [salt.minion      :1432][INFO    ][8830] Starting a new job with PID 8830
2019-03-19 02:28:14,998 [salt.minion      :1711][INFO    ][8830] Returning information for job: 20190319022814942605
2019-03-19 02:28:44,633 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.40669799s left)
2019-03-19 02:28:45,038 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022845025694
2019-03-19 02:28:45,063 [salt.minion      :1432][INFO    ][8916] Starting a new job with PID 8916
2019-03-19 02:28:45,086 [salt.minion      :1711][INFO    ][8916] Returning information for job: 20190319022845025694
2019-03-19 02:29:15,122 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022915110621
2019-03-19 02:29:15,143 [salt.minion      :1432][INFO    ][9217] Starting a new job with PID 9217
2019-03-19 02:29:15,165 [salt.minion      :1711][INFO    ][9217] Returning information for job: 20190319022915110621
2019-03-19 02:29:17,934 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.10543299s left)
2019-03-19 02:29:45,212 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319022945199479
2019-03-19 02:29:45,237 [salt.minion      :1432][INFO    ][9326] Starting a new job with PID 9326
2019-03-19 02:29:45,261 [salt.minion      :1711][INFO    ][9326] Returning information for job: 20190319022945199479
2019-03-19 02:29:51,581 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.45894313s left)
2019-03-19 02:30:15,313 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023015300990
2019-03-19 02:30:15,338 [salt.minion      :1432][INFO    ][9368] Starting a new job with PID 9368
2019-03-19 02:30:15,360 [salt.minion      :1711][INFO    ][9368] Returning information for job: 20190319023015300990
2019-03-19 02:30:25,258 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1778.78207493s left)
2019-03-19 02:30:45,423 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023045410288
2019-03-19 02:30:45,447 [salt.minion      :1432][INFO    ][9492] Starting a new job with PID 9492
2019-03-19 02:30:45,468 [salt.minion      :1711][INFO    ][9492] Returning information for job: 20190319023045410288
2019-03-19 02:30:58,663 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['cmp002', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1745.377285s left)
2019-03-19 02:31:15,646 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023115633119
2019-03-19 02:31:15,670 [salt.minion      :1432][INFO    ][9824] Starting a new job with PID 9824
2019-03-19 02:31:15,693 [salt.minion      :1711][INFO    ][9824] Returning information for job: 20190319023115633119
2019-03-19 02:31:32,180 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1711.85999513s left)
2019-03-19 02:31:45,768 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023145755545
2019-03-19 02:31:45,793 [salt.minion      :1432][INFO    ][9999] Starting a new job with PID 9999
2019-03-19 02:31:45,813 [salt.minion      :1711][INFO    ][9999] Returning information for job: 20190319023145755545
2019-03-19 02:32:05,501 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1678.53887606s left)
2019-03-19 02:32:15,891 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023215877465
2019-03-19 02:32:15,915 [salt.minion      :1432][INFO    ][10156] Starting a new job with PID 10156
2019-03-19 02:32:15,939 [salt.minion      :1711][INFO    ][10156] Returning information for job: 20190319023215877465
2019-03-19 02:32:39,145 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1644.89467311s left)
2019-03-19 02:32:46,029 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023246016690
2019-03-19 02:32:46,054 [salt.minion      :1432][INFO    ][10190] Starting a new job with PID 10190
2019-03-19 02:32:46,075 [salt.minion      :1711][INFO    ][10190] Returning information for job: 20190319023246016690
2019-03-19 02:33:12,582 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1611.45734191s left)
2019-03-19 02:33:16,175 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023316160010
2019-03-19 02:33:16,195 [salt.minion      :1432][INFO    ][10229] Starting a new job with PID 10229
2019-03-19 02:33:16,216 [salt.minion      :1711][INFO    ][10229] Returning information for job: 20190319023316160010
2019-03-19 02:33:46,056 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1577.98447704s left)
2019-03-19 02:33:46,330 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023346312808
2019-03-19 02:33:46,355 [salt.minion      :1432][INFO    ][10265] Starting a new job with PID 10265
2019-03-19 02:33:46,376 [salt.minion      :1711][INFO    ][10265] Returning information for job: 20190319023346312808
2019-03-19 02:34:16,492 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023416480611
2019-03-19 02:34:16,514 [salt.minion      :1432][INFO    ][10301] Starting a new job with PID 10301
2019-03-19 02:34:16,537 [salt.minion      :1711][INFO    ][10301] Returning information for job: 20190319023416480611
2019-03-19 02:34:19,353 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1544.68651891s left)
2019-03-19 02:34:46,660 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023446646351
2019-03-19 02:34:46,684 [salt.minion      :1432][INFO    ][10337] Starting a new job with PID 10337
2019-03-19 02:34:46,708 [salt.minion      :1711][INFO    ][10337] Returning information for job: 20190319023446646351
2019-03-19 02:34:52,971 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1511.06835604s left)
2019-03-19 02:35:16,846 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023516833758
2019-03-19 02:35:16,870 [salt.minion      :1432][INFO    ][10522] Starting a new job with PID 10522
2019-03-19 02:35:16,891 [salt.minion      :1711][INFO    ][10522] Returning information for job: 20190319023516833758
2019-03-19 02:35:26,193 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1477.84707308s left)
2019-03-19 02:35:47,026 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023547014111
2019-03-19 02:35:47,051 [salt.minion      :1432][INFO    ][10571] Starting a new job with PID 10571
2019-03-19 02:35:47,072 [salt.minion      :1711][INFO    ][10571] Returning information for job: 20190319023547014111
2019-03-19 02:35:59,624 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1444.41557312s left)
2019-03-19 02:36:17,224 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023617211618
2019-03-19 02:36:17,250 [salt.minion      :1432][INFO    ][10616] Starting a new job with PID 10616
2019-03-19 02:36:17,272 [salt.minion      :1711][INFO    ][10616] Returning information for job: 20190319023617211618
2019-03-19 02:36:32,992 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1411.04793596s left)
2019-03-19 02:36:47,436 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023647423107
2019-03-19 02:36:47,460 [salt.minion      :1432][INFO    ][10649] Starting a new job with PID 10649
2019-03-19 02:36:47,484 [salt.minion      :1711][INFO    ][10649] Returning information for job: 20190319023647423107
2019-03-19 02:37:06,416 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1377.62401891s left)
2019-03-19 02:37:17,660 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023717646890
2019-03-19 02:37:17,685 [salt.minion      :1432][INFO    ][10691] Starting a new job with PID 10691
2019-03-19 02:37:17,708 [salt.minion      :1711][INFO    ][10691] Returning information for job: 20190319023717646890
2019-03-19 02:37:39,961 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1344.07893991s left)
2019-03-19 02:37:47,686 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023747673614
2019-03-19 02:37:47,710 [salt.minion      :1432][INFO    ][10728] Starting a new job with PID 10728
2019-03-19 02:37:47,730 [salt.minion      :1711][INFO    ][10728] Returning information for job: 20190319023747673614
2019-03-19 02:38:13,268 [salt.loaded.ext.module.maas:1023][INFO    ][7112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1310.77195406s left)
2019-03-19 02:38:17,717 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023817704931
2019-03-19 02:38:17,742 [salt.minion      :1432][INFO    ][10770] Starting a new job with PID 10770
2019-03-19 02:38:17,762 [salt.minion      :1711][INFO    ][10770] Returning information for job: 20190319023817704931
2019-03-19 02:38:46,961 [salt.loaded.ext.module.maas:993 ][INFO    ][7112] Machine hpsmxe mark broken
2019-03-19 02:38:47,704 [salt.loaded.ext.module.maas:996 ][INFO    ][7112] Machine hpsmxe mark fixed
2019-03-19 02:38:47,761 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023847748849
2019-03-19 02:38:47,787 [salt.minion      :1432][INFO    ][10824] Starting a new job with PID 10824
2019-03-19 02:38:47,809 [salt.minion      :1711][INFO    ][10824] Returning information for job: 20190319023847748849
2019-03-19 02:38:48,895 [salt.loaded.ext.module.maas:684 ][INFO    ][7112] deploymachines hwe_kernel=ga-16.04 system_id=hpsmxe distro_series=xenial
2019-03-19 02:38:51,586 [salt.loaded.ext.module.maas:160 ][ERROR   ][7112] Failed for object kvm02 reason Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node.
2019-03-19 02:38:51,589 [salt.state       :302 ][ERROR   ][7112] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {'kvm02': "Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node."}, 'success': []}
2019-03-19 02:38:51,589 [salt.state       :1951][INFO    ][7112] Completed state [maas.wait_for_machine_status] at time 02:38:51.589539 duration_in_ms=977559.466
2019-03-19 02:38:51,599 [salt.minion      :1711][INFO    ][7112] Returning information for job: 20190319022229145125
2019-03-19 02:39:02,348 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command pillar.get with jid 20190319023902335373
2019-03-19 02:39:02,371 [salt.minion      :1432][INFO    ][10898] Starting a new job with PID 10898
2019-03-19 02:39:02,376 [salt.minion      :1711][INFO    ][10898] Returning information for job: 20190319023902335373
2019-03-19 02:39:02,929 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command service.status with jid 20190319023902916045
2019-03-19 02:39:02,951 [salt.minion      :1432][INFO    ][10903] Starting a new job with PID 10903
2019-03-19 02:39:03,320 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10903] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-19 02:39:03,353 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10903] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-19 02:39:03,369 [salt.minion      :1711][INFO    ][10903] Returning information for job: 20190319023902916045
2019-03-19 02:39:03,852 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319023903838555
2019-03-19 02:39:03,875 [salt.minion      :1432][INFO    ][10914] Starting a new job with PID 10914
2019-03-19 02:39:07,365 [salt.state       :915 ][INFO    ][10914] Loading fresh modules for state activity
2019-03-19 02:39:07,782 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command 'salt-minion --version' in directory '/root'
2019-03-19 02:39:08,092 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command 'salt-minion --version' in directory '/root'
2019-03-19 02:39:08,951 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command 'salt-minion --version' in directory '/root'
2019-03-19 02:39:09,267 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command 'salt-minion --version' in directory '/root'
2019-03-19 02:39:10,647 [salt.state       :1780][INFO    ][10914] Running state [salt-minion] at time 02:39:10.647136
2019-03-19 02:39:10,647 [salt.state       :1813][INFO    ][10914] Executing state pkg.installed for [salt-minion]
2019-03-19 02:39:10,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-19 02:39:10,753 [salt.state       :300 ][INFO    ][10914] All specified packages are already installed
2019-03-19 02:39:10,754 [salt.state       :1951][INFO    ][10914] Completed state [salt-minion] at time 02:39:10.754118 duration_in_ms=106.983
2019-03-19 02:39:10,754 [salt.state       :1780][INFO    ][10914] Running state [salt_minion_dependency_packages] at time 02:39:10.754550
2019-03-19 02:39:10,754 [salt.state       :1813][INFO    ][10914] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-19 02:39:10,763 [salt.state       :300 ][INFO    ][10914] All specified packages are already installed
2019-03-19 02:39:10,763 [salt.state       :1951][INFO    ][10914] Completed state [salt_minion_dependency_packages] at time 02:39:10.763428 duration_in_ms=8.877
2019-03-19 02:39:10,767 [salt.state       :1780][INFO    ][10914] Running state [/etc/salt/minion.d/minion.conf] at time 02:39:10.767263
2019-03-19 02:39:10,767 [salt.state       :1813][INFO    ][10914] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-19 02:39:10,991 [salt.state       :300 ][INFO    ][10914] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-19 02:39:10,992 [salt.state       :1951][INFO    ][10914] Completed state [/etc/salt/minion.d/minion.conf] at time 02:39:10.991957 duration_in_ms=224.694
2019-03-19 02:39:10,992 [salt.state       :1780][INFO    ][10914] Running state [python-netaddr] at time 02:39:10.992345
2019-03-19 02:39:10,992 [salt.state       :1813][INFO    ][10914] Executing state pkg.installed for [python-netaddr]
2019-03-19 02:39:11,003 [salt.state       :300 ][INFO    ][10914] All specified packages are already installed
2019-03-19 02:39:11,004 [salt.state       :1951][INFO    ][10914] Completed state [python-netaddr] at time 02:39:11.004006 duration_in_ms=11.661
2019-03-19 02:39:11,008 [salt.state       :1780][INFO    ][10914] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:39:11.008611
2019-03-19 02:39:11,009 [salt.state       :1813][INFO    ][10914] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-19 02:39:11,021 [salt.state       :300 ][INFO    ][10914] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-19 02:39:11,022 [salt.state       :1951][INFO    ][10914] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:39:11.021980 duration_in_ms=13.369
2019-03-19 02:39:11,023 [salt.state       :1780][INFO    ][10914] Running state [salt-minion] at time 02:39:11.023453
2019-03-19 02:39:11,023 [salt.state       :1813][INFO    ][10914] Executing state service.running for [salt-minion]
2019-03-19 02:39:11,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-19 02:39:11,065 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-19 02:39:11,084 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-19 02:39:11,102 [salt.state       :300 ][INFO    ][10914] The service salt-minion is already running
2019-03-19 02:39:11,102 [salt.state       :1951][INFO    ][10914] Completed state [salt-minion] at time 02:39:11.102727 duration_in_ms=79.273
2019-03-19 02:39:11,105 [salt.state       :1780][INFO    ][10914] Running state [/etc/salt/grains.d] at time 02:39:11.105144
2019-03-19 02:39:11,105 [salt.state       :1813][INFO    ][10914] Executing state file.directory for [/etc/salt/grains.d]
2019-03-19 02:39:11,107 [salt.state       :300 ][INFO    ][10914] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-19 02:39:11,107 [salt.state       :1951][INFO    ][10914] Completed state [/etc/salt/grains.d] at time 02:39:11.107449 duration_in_ms=2.305
2019-03-19 02:39:11,108 [salt.state       :1780][INFO    ][10914] Running state [/etc/salt/grains] at time 02:39:11.108544
2019-03-19 02:39:11,109 [salt.state       :1813][INFO    ][10914] Executing state file.managed for [/etc/salt/grains]
2019-03-19 02:39:11,109 [salt.state       :300 ][INFO    ][10914] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-19 02:39:11,110 [salt.state       :1951][INFO    ][10914] Completed state [/etc/salt/grains] at time 02:39:11.110174 duration_in_ms=1.629
2019-03-19 02:39:11,110 [salt.state       :1780][INFO    ][10914] Running state [/etc/salt/grains.d/placeholder] at time 02:39:11.110901
2019-03-19 02:39:11,111 [salt.state       :1813][INFO    ][10914] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-19 02:39:11,112 [salt.state       :300 ][INFO    ][10914] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-19 02:39:11,112 [salt.state       :1951][INFO    ][10914] Completed state [/etc/salt/grains.d/placeholder] at time 02:39:11.112481 duration_in_ms=1.579
2019-03-19 02:39:11,113 [salt.state       :1780][INFO    ][10914] Running state [/etc/salt/grains.d/sphinx] at time 02:39:11.113236
2019-03-19 02:39:11,113 [salt.state       :1813][INFO    ][10914] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-19 02:39:11,122 [salt.state       :300 ][INFO    ][10914] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-19 02:39:11,123 [salt.state       :1951][INFO    ][10914] Completed state [/etc/salt/grains.d/sphinx] at time 02:39:11.123192 duration_in_ms=9.955
2019-03-19 02:39:11,126 [salt.state       :1780][INFO    ][10914] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:39:11.126435
2019-03-19 02:39:11,126 [salt.state       :1813][INFO    ][10914] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-19 02:39:11,127 [salt.state       :300 ][INFO    ][10914] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-19 02:39:11,127 [salt.state       :1951][INFO    ][10914] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:39:11.127731 duration_in_ms=1.295
2019-03-19 02:39:11,128 [salt.state       :1780][INFO    ][10914] Running state [/etc/salt/grains.d/dns_records] at time 02:39:11.128419
2019-03-19 02:39:11,128 [salt.state       :1813][INFO    ][10914] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-19 02:39:11,140 [salt.state       :300 ][INFO    ][10914] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-19 02:39:11,141 [salt.state       :1951][INFO    ][10914] Completed state [/etc/salt/grains.d/dns_records] at time 02:39:11.141011 duration_in_ms=12.591
2019-03-19 02:39:11,142 [salt.state       :1780][INFO    ][10914] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:39:11.142294
2019-03-19 02:39:11,142 [salt.state       :1813][INFO    ][10914] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-19 02:39:11,143 [salt.state       :300 ][INFO    ][10914] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-19 02:39:11,143 [salt.state       :1951][INFO    ][10914] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:39:11.143475 duration_in_ms=1.18
2019-03-19 02:39:11,144 [salt.state       :1780][INFO    ][10914] Running state [/etc/salt/grains.d/salt] at time 02:39:11.144148
2019-03-19 02:39:11,144 [salt.state       :1813][INFO    ][10914] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-19 02:39:11,158 [salt.state       :300 ][INFO    ][10914] File /etc/salt/grains.d/salt is in the correct state
2019-03-19 02:39:11,159 [salt.state       :1951][INFO    ][10914] Completed state [/etc/salt/grains.d/salt] at time 02:39:11.158993 duration_in_ms=14.844
2019-03-19 02:39:11,160 [salt.state       :1780][INFO    ][10914] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:39:11.160200
2019-03-19 02:39:11,160 [salt.state       :1813][INFO    ][10914] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-19 02:39:11,161 [salt.state       :300 ][INFO    ][10914] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-19 02:39:11,161 [salt.state       :1951][INFO    ][10914] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:39:11.161360 duration_in_ms=1.16
2019-03-19 02:39:11,164 [salt.state       :1780][INFO    ][10914] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:39:11.164060
2019-03-19 02:39:11,164 [salt.state       :1813][INFO    ][10914] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-19 02:39:11,164 [salt.state       :300 ][INFO    ][10914] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-19 02:39:11,165 [salt.state       :1951][INFO    ][10914] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:39:11.165240 duration_in_ms=1.181
2019-03-19 02:39:11,166 [salt.state       :1780][INFO    ][10914] Running state [mine.update] at time 02:39:11.166175
2019-03-19 02:39:11,166 [salt.state       :1813][INFO    ][10914] Executing state module.wait for [mine.update]
2019-03-19 02:39:11,167 [salt.state       :300 ][INFO    ][10914] No changes made for mine.update
2019-03-19 02:39:11,167 [salt.state       :1951][INFO    ][10914] Completed state [mine.update] at time 02:39:11.167264 duration_in_ms=1.089
2019-03-19 02:39:11,167 [salt.state       :1780][INFO    ][10914] Running state [ca-certificates] at time 02:39:11.167618
2019-03-19 02:39:11,168 [salt.state       :1813][INFO    ][10914] Executing state pkg.installed for [ca-certificates]
2019-03-19 02:39:11,178 [salt.state       :300 ][INFO    ][10914] All specified packages are already installed
2019-03-19 02:39:11,178 [salt.state       :1951][INFO    ][10914] Completed state [ca-certificates] at time 02:39:11.178430 duration_in_ms=10.811
2019-03-19 02:39:11,179 [salt.state       :1780][INFO    ][10914] Running state [update-ca-certificates] at time 02:39:11.179360
2019-03-19 02:39:11,179 [salt.state       :1813][INFO    ][10914] Executing state cmd.wait for [update-ca-certificates]
2019-03-19 02:39:11,180 [salt.state       :300 ][INFO    ][10914] No changes made for update-ca-certificates
2019-03-19 02:39:11,180 [salt.state       :1951][INFO    ][10914] Completed state [update-ca-certificates] at time 02:39:11.180422 duration_in_ms=1.063
2019-03-19 02:39:11,180 [salt.state       :1780][INFO    ][10914] Running state [iptables] at time 02:39:11.180763
2019-03-19 02:39:11,181 [salt.state       :1813][INFO    ][10914] Executing state pkg.installed for [iptables]
2019-03-19 02:39:11,190 [salt.state       :300 ][INFO    ][10914] All specified packages are already installed
2019-03-19 02:39:11,190 [salt.state       :1951][INFO    ][10914] Completed state [iptables] at time 02:39:11.190331 duration_in_ms=9.569
2019-03-19 02:39:11,190 [salt.state       :1780][INFO    ][10914] Running state [iptables-persistent] at time 02:39:11.190662
2019-03-19 02:39:11,190 [salt.state       :1813][INFO    ][10914] Executing state pkg.installed for [iptables-persistent]
2019-03-19 02:39:11,199 [salt.state       :300 ][INFO    ][10914] All specified packages are already installed
2019-03-19 02:39:11,199 [salt.state       :1951][INFO    ][10914] Completed state [iptables-persistent] at time 02:39:11.199886 duration_in_ms=9.225
2019-03-19 02:39:11,201 [salt.state       :1780][INFO    ][10914] Running state [iptables_modules_v4_load] at time 02:39:11.201117
2019-03-19 02:39:11,201 [salt.state       :1813][INFO    ][10914] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-19 02:39:11,202 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command 'lsmod' in directory '/root'
2019-03-19 02:39:11,226 [salt.state       :300 ][INFO    ][10914] Kernel modules iptable_filter, ip_tables are already present
2019-03-19 02:39:11,227 [salt.state       :1951][INFO    ][10914] Completed state [iptables_modules_v4_load] at time 02:39:11.227255 duration_in_ms=26.138
2019-03-19 02:39:11,228 [salt.state       :1780][INFO    ][10914] Running state [/etc/iptables/rules.v4] at time 02:39:11.228156
2019-03-19 02:39:11,228 [salt.state       :1813][INFO    ][10914] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-19 02:39:11,338 [salt.state       :300 ][INFO    ][10914] File /etc/iptables/rules.v4 is in the correct state
2019-03-19 02:39:11,339 [salt.state       :1951][INFO    ][10914] Completed state [/etc/iptables/rules.v4] at time 02:39:11.338961 duration_in_ms=110.804
2019-03-19 02:39:11,340 [salt.state       :1780][INFO    ][10914] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:39:11.340140
2019-03-19 02:39:11,340 [salt.state       :1813][INFO    ][10914] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-19 02:39:11,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-19 02:39:11,360 [salt.state       :300 ][INFO    ][10914] onlyif execution failed
2019-03-19 02:39:11,361 [salt.state       :1951][INFO    ][10914] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:39:11.361366 duration_in_ms=21.225
2019-03-19 02:39:11,363 [salt.state       :1780][INFO    ][10914] Running state [netfilter-persistent] at time 02:39:11.363051
2019-03-19 02:39:11,363 [salt.state       :1813][INFO    ][10914] Executing state service.running for [netfilter-persistent]
2019-03-19 02:39:11,364 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-19 02:39:11,386 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-19 02:39:11,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-19 02:39:11,424 [salt.state       :300 ][INFO    ][10914] The service netfilter-persistent is already running
2019-03-19 02:39:11,425 [salt.state       :1951][INFO    ][10914] Completed state [netfilter-persistent] at time 02:39:11.425113 duration_in_ms=62.062
2019-03-19 02:39:11,426 [salt.state       :1780][INFO    ][10914] Running state [iptables_extra.remove_stale_tables] at time 02:39:11.426425
2019-03-19 02:39:11,426 [salt.state       :1813][INFO    ][10914] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-19 02:39:11,427 [salt.state       :300 ][INFO    ][10914] No changes made for iptables_extra.remove_stale_tables
2019-03-19 02:39:11,427 [salt.state       :1951][INFO    ][10914] Completed state [iptables_extra.remove_stale_tables] at time 02:39:11.427779 duration_in_ms=1.354
2019-03-19 02:39:11,428 [salt.state       :1780][INFO    ][10914] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:39:11.428151
2019-03-19 02:39:11,428 [salt.state       :1813][INFO    ][10914] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-19 02:39:11,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10914] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-19 02:39:11,446 [salt.state       :300 ][INFO    ][10914] onlyif execution failed
2019-03-19 02:39:11,446 [salt.state       :1951][INFO    ][10914] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:39:11.446438 duration_in_ms=18.286
2019-03-19 02:39:11,448 [salt.state       :1780][INFO    ][10914] Running state [/etc/iptables/rules.v6] at time 02:39:11.447968
2019-03-19 02:39:11,448 [salt.state       :1813][INFO    ][10914] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-19 02:39:11,449 [salt.state       :300 ][INFO    ][10914] File /etc/iptables/rules.v6 is not present
2019-03-19 02:39:11,449 [salt.state       :1951][INFO    ][10914] Completed state [/etc/iptables/rules.v6] at time 02:39:11.449482 duration_in_ms=1.514
2019-03-19 02:39:11,450 [salt.state       :1780][INFO    ][10914] Running state [iptables_extra.flush_all] at time 02:39:11.450561
2019-03-19 02:39:11,451 [salt.state       :1813][INFO    ][10914] Executing state module.wait for [iptables_extra.flush_all]
2019-03-19 02:39:11,451 [salt.state       :300 ][INFO    ][10914] No changes made for iptables_extra.flush_all
2019-03-19 02:39:11,451 [salt.state       :1951][INFO    ][10914] Completed state [iptables_extra.flush_all] at time 02:39:11.451755 duration_in_ms=1.193
2019-03-19 02:39:11,456 [salt.minion      :1711][INFO    ][10914] Returning information for job: 20190319023903838555
2019-03-19 02:39:12,083 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319023912072608
2019-03-19 02:39:12,107 [salt.minion      :1432][INFO    ][11005] Starting a new job with PID 11005
2019-03-19 02:39:12,770 [salt.state       :915 ][INFO    ][11005] Loading fresh modules for state activity
2019-03-19 02:39:13,263 [salt.state       :1780][INFO    ][11005] Running state [maas-rack-controller] at time 02:39:13.263885
2019-03-19 02:39:13,264 [salt.state       :1813][INFO    ][11005] Executing state pkg.installed for [maas-rack-controller]
2019-03-19 02:39:13,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11005] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-19 02:39:13,380 [salt.state       :300 ][INFO    ][11005] All specified packages are already installed
2019-03-19 02:39:13,381 [salt.state       :1951][INFO    ][11005] Completed state [maas-rack-controller] at time 02:39:13.381082 duration_in_ms=117.196
2019-03-19 02:39:13,381 [salt.state       :1780][INFO    ][11005] Running state [ipmitool] at time 02:39:13.381334
2019-03-19 02:39:13,381 [salt.state       :1813][INFO    ][11005] Executing state pkg.installed for [ipmitool]
2019-03-19 02:39:13,385 [salt.state       :300 ][INFO    ][11005] All specified packages are already installed
2019-03-19 02:39:13,386 [salt.state       :1951][INFO    ][11005] Completed state [ipmitool] at time 02:39:13.386114 duration_in_ms=4.781
2019-03-19 02:39:13,388 [salt.state       :1780][INFO    ][11005] Running state [/etc/maas/rackd.conf] at time 02:39:13.388040
2019-03-19 02:39:13,388 [salt.state       :1813][INFO    ][11005] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-19 02:39:13,389 [salt.state       :300 ][INFO    ][11005] No changes needed to be made
2019-03-19 02:39:13,389 [salt.state       :1951][INFO    ][11005] Completed state [/etc/maas/rackd.conf] at time 02:39:13.389133 duration_in_ms=1.093
2019-03-19 02:39:13,389 [salt.state       :1780][INFO    ][11005] Running state [/etc/maas/rackd.conf] at time 02:39:13.389306
2019-03-19 02:39:13,389 [salt.state       :1813][INFO    ][11005] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-19 02:39:13,389 [salt.loaded.int.states.file:2298][WARNING ][11005] 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-03-19 02:39:13,390 [salt.state       :300 ][INFO    ][11005] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-19 02:39:13,390 [salt.state       :1951][INFO    ][11005] Completed state [/etc/maas/rackd.conf] at time 02:39:13.390243 duration_in_ms=0.937
2019-03-19 02:39:13,390 [salt.state       :1780][INFO    ][11005] Running state [maas-rackd] at time 02:39:13.390950
2019-03-19 02:39:13,391 [salt.state       :1813][INFO    ][11005] Executing state service.running for [maas-rackd]
2019-03-19 02:39:13,391 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11005] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-19 02:39:13,425 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11005] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-19 02:39:13,442 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11005] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-19 02:39:13,457 [salt.state       :300 ][INFO    ][11005] The service maas-rackd is already running
2019-03-19 02:39:13,458 [salt.state       :1951][INFO    ][11005] Completed state [maas-rackd] at time 02:39:13.457988 duration_in_ms=67.038
2019-03-19 02:39:13,459 [salt.minion      :1711][INFO    ][11005] Returning information for job: 20190319023912072608
2019-03-19 02:39:14,047 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319023914039577
2019-03-19 02:39:14,070 [salt.minion      :1432][INFO    ][11030] Starting a new job with PID 11030
2019-03-19 02:39:14,729 [salt.state       :915 ][INFO    ][11030] Loading fresh modules for state activity
2019-03-19 02:39:15,478 [salt.state       :1780][INFO    ][11030] Running state [maas-region-controller] at time 02:39:15.478320
2019-03-19 02:39:15,478 [salt.state       :1813][INFO    ][11030] Executing state pkg.installed for [maas-region-controller]
2019-03-19 02:39:15,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-19 02:39:15,563 [salt.state       :300 ][INFO    ][11030] All specified packages are already installed
2019-03-19 02:39:15,564 [salt.state       :1951][INFO    ][11030] Completed state [maas-region-controller] at time 02:39:15.563987 duration_in_ms=85.667
2019-03-19 02:39:15,564 [salt.state       :1780][INFO    ][11030] Running state [python-oauth] at time 02:39:15.564292
2019-03-19 02:39:15,564 [salt.state       :1813][INFO    ][11030] Executing state pkg.installed for [python-oauth]
2019-03-19 02:39:15,571 [salt.state       :300 ][INFO    ][11030] All specified packages are already installed
2019-03-19 02:39:15,572 [salt.state       :1951][INFO    ][11030] Completed state [python-oauth] at time 02:39:15.572049 duration_in_ms=7.757
2019-03-19 02:39:15,574 [salt.state       :1780][INFO    ][11030] Running state [/etc/maas/regiond.conf] at time 02:39:15.574773
2019-03-19 02:39:15,575 [salt.state       :1813][INFO    ][11030] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-19 02:39:15,615 [salt.state       :300 ][INFO    ][11030] No changes needed to be made
2019-03-19 02:39:15,616 [salt.state       :1951][INFO    ][11030] Completed state [/etc/maas/regiond.conf] at time 02:39:15.616003 duration_in_ms=41.229
2019-03-19 02:39:15,616 [salt.state       :1780][INFO    ][11030] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:39:15.616755
2019-03-19 02:39:15,617 [salt.state       :1813][INFO    ][11030] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-19 02:39:15,698 [salt.state       :300 ][INFO    ][11030] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-19 02:39:15,698 [salt.state       :1951][INFO    ][11030] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:39:15.698855 duration_in_ms=82.1
2019-03-19 02:39:15,699 [salt.state       :1780][INFO    ][11030] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:39:15.699449
2019-03-19 02:39:15,699 [salt.state       :1813][INFO    ][11030] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-19 02:39:15,712 [salt.state       :300 ][INFO    ][11030] No changes needed to be made
2019-03-19 02:39:15,712 [salt.state       :1951][INFO    ][11030] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:39:15.712586 duration_in_ms=13.137
2019-03-19 02:39:15,713 [salt.state       :1780][INFO    ][11030] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:39:15.713166
2019-03-19 02:39:15,713 [salt.state       :1813][INFO    ][11030] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-19 02:39:15,745 [salt.state       :300 ][INFO    ][11030] No changes needed to be made
2019-03-19 02:39:15,746 [salt.state       :1951][INFO    ][11030] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:39:15.746081 duration_in_ms=32.914
2019-03-19 02:39:15,747 [salt.state       :1780][INFO    ][11030] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:39:15.747047
2019-03-19 02:39:15,747 [salt.state       :1813][INFO    ][11030] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-19 02:39:15,779 [salt.state       :300 ][INFO    ][11030] No changes needed to be made
2019-03-19 02:39:15,779 [salt.state       :1951][INFO    ][11030] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:39:15.779924 duration_in_ms=32.876
2019-03-19 02:39:15,780 [salt.state       :1780][INFO    ][11030] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:39:15.780506
2019-03-19 02:39:15,780 [salt.state       :1813][INFO    ][11030] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-19 02:39:15,794 [salt.state       :300 ][INFO    ][11030] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-19 02:39:15,795 [salt.state       :1951][INFO    ][11030] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:39:15.795214 duration_in_ms=14.708
2019-03-19 02:39:15,797 [salt.state       :1780][INFO    ][11030] Running state [a2enmod headers] at time 02:39:15.797365
2019-03-19 02:39:15,797 [salt.state       :1813][INFO    ][11030] Executing state cmd.run for [a2enmod headers]
2019-03-19 02:39:15,798 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command 'a2enmod headers' in directory '/root'
2019-03-19 02:39:15,872 [salt.state       :300 ][INFO    ][11030] {'pid': 11049, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-19 02:39:15,873 [salt.state       :1951][INFO    ][11030] Completed state [a2enmod headers] at time 02:39:15.873382 duration_in_ms=76.016
2019-03-19 02:39:15,874 [salt.state       :1780][INFO    ][11030] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:39:15.874006
2019-03-19 02:39:15,874 [salt.state       :1813][INFO    ][11030] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-19 02:39:15,893 [salt.state       :300 ][INFO    ][11030] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-19 02:39:15,894 [salt.state       :1951][INFO    ][11030] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:39:15.894183 duration_in_ms=20.178
2019-03-19 02:39:15,895 [salt.state       :1780][INFO    ][11030] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:39:15.895082
2019-03-19 02:39:15,895 [salt.state       :1813][INFO    ][11030] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-19 02:39:15,980 [salt.state       :300 ][INFO    ][11030] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-19 02:39:15,980 [salt.state       :1951][INFO    ][11030] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:39:15.980754 duration_in_ms=85.672
2019-03-19 02:39:15,981 [salt.state       :1780][INFO    ][11030] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:39:15.981465
2019-03-19 02:39:15,981 [salt.state       :1813][INFO    ][11030] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-19 02:39:16,052 [salt.state       :300 ][INFO    ][11030] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-19 02:39:16,052 [salt.state       :1951][INFO    ][11030] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:39:16.052302 duration_in_ms=70.838
2019-03-19 02:39:16,052 [salt.state       :1780][INFO    ][11030] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:39:16.052908
2019-03-19 02:39:16,053 [salt.state       :1813][INFO    ][11030] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-19 02:39:16,117 [salt.state       :300 ][INFO    ][11030] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-19 02:39:16,118 [salt.state       :1951][INFO    ][11030] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:39:16.118125 duration_in_ms=65.218
2019-03-19 02:39:16,118 [salt.state       :1780][INFO    ][11030] Running state [/root/.pgpass] at time 02:39:16.118415
2019-03-19 02:39:16,118 [salt.state       :1813][INFO    ][11030] Executing state file.managed for [/root/.pgpass]
2019-03-19 02:39:16,166 [salt.state       :300 ][INFO    ][11030] File /root/.pgpass is in the correct state
2019-03-19 02:39:16,166 [salt.state       :1951][INFO    ][11030] Completed state [/root/.pgpass] at time 02:39:16.166578 duration_in_ms=48.161
2019-03-19 02:39:16,174 [salt.state       :1780][INFO    ][11030] Running state [maas-region syncdb --noinput] at time 02:39:16.174648
2019-03-19 02:39:16,175 [salt.state       :1813][INFO    ][11030] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-19 02:39:16,176 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-19 02:39:18,112 [salt.state       :300 ][INFO    ][11030] {'pid': 11062, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, metadataserver, contenttypes, auth, maasserver, sites, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-19 02:39:18,113 [salt.state       :1951][INFO    ][11030] Completed state [maas-region syncdb --noinput] at time 02:39:18.113054 duration_in_ms=1938.406
2019-03-19 02:39:18,113 [salt.state       :2022][WARNING ][11030] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-19 02:39:18,116 [salt.state       :1780][INFO    ][11030] Running state [maas-regiond] at time 02:39:18.116353
2019-03-19 02:39:18,116 [salt.state       :1813][INFO    ][11030] Executing state service.running for [maas-regiond]
2019-03-19 02:39:18,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-19 02:39:18,155 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-19 02:39:18,173 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-19 02:39:18,190 [salt.state       :300 ][INFO    ][11030] The service maas-regiond is already running
2019-03-19 02:39:18,190 [salt.state       :1951][INFO    ][11030] Completed state [maas-regiond] at time 02:39:18.190763 duration_in_ms=74.41
2019-03-19 02:39:18,193 [salt.state       :1780][INFO    ][11030] Running state [bind9] at time 02:39:18.193176
2019-03-19 02:39:18,193 [salt.state       :1813][INFO    ][11030] Executing state service.running for [bind9]
2019-03-19 02:39:18,194 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-19 02:39:18,212 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-19 02:39:18,228 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-19 02:39:18,243 [salt.state       :300 ][INFO    ][11030] The service bind9 is already running
2019-03-19 02:39:18,244 [salt.state       :1951][INFO    ][11030] Completed state [bind9] at time 02:39:18.244296 duration_in_ms=51.12
2019-03-19 02:39:18,246 [salt.state       :1780][INFO    ][11030] Running state [apache2] at time 02:39:18.246251
2019-03-19 02:39:18,246 [salt.state       :1813][INFO    ][11030] Executing state service.running for [apache2]
2019-03-19 02:39:18,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-19 02:39:18,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-19 02:39:18,279 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-19 02:39:18,299 [salt.state       :300 ][INFO    ][11030] The service apache2 is already running
2019-03-19 02:39:18,299 [salt.state       :1951][INFO    ][11030] Completed state [apache2] at time 02:39:18.299777 duration_in_ms=53.525
2019-03-19 02:39:18,301 [salt.state       :1780][INFO    ][11030] Running state [maasng.wait_for_http_code] at time 02:39:18.301351
2019-03-19 02:39:18,301 [salt.state       :1813][INFO    ][11030] Executing state module.run for [maasng.wait_for_http_code]
2019-03-19 02:39:18,302 [salt.utils.decorators:613 ][WARNING ][11030] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:39:18,431 [salt.state       :300 ][INFO    ][11030] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-19 02:39:18,432 [salt.state       :1951][INFO    ][11030] Completed state [maasng.wait_for_http_code] at time 02:39:18.431877 duration_in_ms=130.525
2019-03-19 02:39:18,433 [salt.state       :1780][INFO    ][11030] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:39:18.433386
2019-03-19 02:39:18,434 [salt.state       :1813][INFO    ][11030] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-19 02:39:18,434 [salt.state       :300 ][INFO    ][11030] /var/lib/maas/.setup_admin exists
2019-03-19 02:39:18,435 [salt.state       :1951][INFO    ][11030] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:39:18.435134 duration_in_ms=1.747
2019-03-19 02:39:18,436 [salt.state       :1780][INFO    ][11030] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:39:18.436391
2019-03-19 02:39:18,436 [salt.state       :1813][INFO    ][11030] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-19 02:39:18,438 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11030] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-19 02:39:19,953 [salt.state       :300 ][INFO    ][11030] {'pid': 11083, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-19 02:39:19,953 [salt.state       :1951][INFO    ][11030] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:39:19.953435 duration_in_ms=1517.045
2019-03-19 02:39:19,956 [salt.state       :1780][INFO    ][11030] Running state [maas_region_boot_source_resources_mirror] at time 02:39:19.956703
2019-03-19 02:39:19,956 [salt.state       :1813][INFO    ][11030] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-19 02:39:20,048 [salt.state       :300 ][INFO    ][11030] {'changes': {}}
2019-03-19 02:39:20,048 [salt.state       :1951][INFO    ][11030] Completed state [maas_region_boot_source_resources_mirror] at time 02:39:20.048898 duration_in_ms=92.194
2019-03-19 02:39:20,050 [salt.state       :1780][INFO    ][11030] Running state [maasng.boot_resources_import] at time 02:39:20.049975
2019-03-19 02:39:20,050 [salt.state       :1813][INFO    ][11030] Executing state module.run for [maasng.boot_resources_import]
2019-03-19 02:39:20,051 [salt.utils.decorators:613 ][WARNING ][11030] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:39:20,168 [salt.loaded.ext.module.maasng:1600][INFO    ][11030] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-19 02:39:25,243 [salt.loaded.ext.module.maasng:1600][INFO    ][11030] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-19 02:39:29,119 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319023929106674
2019-03-19 02:39:29,145 [salt.minion      :1432][INFO    ][11133] Starting a new job with PID 11133
2019-03-19 02:39:29,167 [salt.minion      :1711][INFO    ][11133] Returning information for job: 20190319023929106674
2019-03-19 02:39:30,306 [salt.loaded.ext.module.maasng:1600][INFO    ][11030] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-03-19 02:39:35,431 [salt.state       :300 ][INFO    ][11030] {'ret': True}
2019-03-19 02:39:35,432 [salt.state       :1951][INFO    ][11030] Completed state [maasng.boot_resources_import] at time 02:39:35.432343 duration_in_ms=15382.367
2019-03-19 02:39:35,433 [salt.state       :1780][INFO    ][11030] Running state [maas_region_boot_sources_selection_xenial] at time 02:39:35.433698
2019-03-19 02:39:35,434 [salt.state       :1813][INFO    ][11030] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-19 02:39:35,635 [salt.state       :300 ][INFO    ][11030] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-19 02:39:35,635 [salt.state       :1951][INFO    ][11030] Completed state [maas_region_boot_sources_selection_xenial] at time 02:39:35.635644 duration_in_ms=201.947
2019-03-19 02:39:35,637 [salt.state       :1780][INFO    ][11030] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:39:35.637053
2019-03-19 02:39:35,637 [salt.state       :1813][INFO    ][11030] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-19 02:39:35,638 [salt.utils.decorators:613 ][WARNING ][11030] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:39:35,638 [salt.loaded.ext.module.maasng:1771][INFO    ][11030] boot-sources sync initiated for ALL Rack's
2019-03-19 02:39:36,743 [salt.state       :300 ][INFO    ][11030] {'ret': True}
2019-03-19 02:39:36,743 [salt.state       :1951][INFO    ][11030] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:39:36.743764 duration_in_ms=1106.709
2019-03-19 02:39:36,746 [salt.state       :1780][INFO    ][11030] Running state [maas.process_maas_config] at time 02:39:36.746181
2019-03-19 02:39:36,746 [salt.state       :1813][INFO    ][11030] Executing state module.run for [maas.process_maas_config]
2019-03-19 02:39:36,747 [salt.utils.decorators:613 ][WARNING ][11030] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:39:36,748 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=enable_http_proxy value=True
2019-03-19 02:39:36,817 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=upstream_dns value=8.8.8.8
2019-03-19 02:39:36,881 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=commissioning_distro_series value=xenial
2019-03-19 02:39:36,953 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=default_osystem value=ubuntu
2019-03-19 02:39:37,018 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=active_discovery_interval value=600
2019-03-19 02:39:37,072 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=dnssec_validation value=no
2019-03-19 02:39:37,126 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=maas_name value=mas01
2019-03-19 02:39:39,782 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=network_discovery value=enabled
2019-03-19 02:39:39,883 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=enable_third_party_drivers value=True
2019-03-19 02:39:39,934 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=default_storage_layout value=lvm
2019-03-19 02:39:39,976 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=ntp_external_only value=True
2019-03-19 02:39:40,039 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-19 02:39:40,085 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=default_distro_series value=xenial
2019-03-19 02:39:40,144 [salt.loaded.ext.module.maas:92  ][INFO    ][11030] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-03-19 02:39:40,248 [salt.state       :300 ][INFO    ][11030] {'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-03-19 02:39:40,249 [salt.state       :1951][INFO    ][11030] Completed state [maas.process_maas_config] at time 02:39:40.249141 duration_in_ms=3502.96
2019-03-19 02:39:40,249 [salt.state       :1780][INFO    ][11030] Running state [pxe_admin] at time 02:39:40.249743
2019-03-19 02:39:40,250 [salt.state       :1813][INFO    ][11030] Executing state maasng.fabric_present for [pxe_admin]
2019-03-19 02:39:40,295 [salt.loaded.ext.module.maasng:945 ][INFO    ][11030] [{u'id': 0, 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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 1, 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'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'psqknd', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin', u'class_type': u''}]
2019-03-19 02:39:40,343 [salt.loaded.ext.module.maasng:1008][WARNING ][11030] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-19 02:39:40,343 [salt.loaded.ext.module.maasng:1011][WARNING ][11030] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-19 02:39:40,385 [salt.state       :300 ][INFO    ][11030] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-19 02:39:40,386 [salt.state       :1951][INFO    ][11030] Completed state [pxe_admin] at time 02:39:40.386020 duration_in_ms=136.276
2019-03-19 02:39:40,386 [salt.state       :1780][INFO    ][11030] Running state [vlan 0] at time 02:39:40.386322
2019-03-19 02:39:40,386 [salt.state       :1813][INFO    ][11030] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-19 02:39:40,427 [salt.loaded.ext.module.maasng:945 ][INFO    ][11030] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'psqknd', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-19 02:39:40,526 [salt.loaded.ext.module.maasng:945 ][INFO    ][11030] [{u'id': 0, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'psqknd', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-19 02:39:40,802 [salt.loaded.ext.module.maasng:945 ][INFO    ][11030] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'psqknd', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-19 02:39:40,890 [salt.state       :300 ][INFO    ][11030] {'new': 'Vlan untagged was updated'}
2019-03-19 02:39:40,890 [salt.state       :1951][INFO    ][11030] Completed state [vlan 0] at time 02:39:40.890474 duration_in_ms=504.151
2019-03-19 02:39:40,892 [salt.state       :1780][INFO    ][11030] Running state [192.168.11.0/24] at time 02:39:40.891988
2019-03-19 02:39:40,892 [salt.state       :1813][INFO    ][11030] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-19 02:39:41,057 [salt.loaded.ext.module.maasng:945 ][INFO    ][11030] [{u'id': 0, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'psqknd', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-19 02:39:41,058 [salt.loaded.ext.module.maasng:1235][WARNING ][11030] Ignoring parameter vlan:0
2019-03-19 02:39:41,129 [salt.state       :300 ][INFO    ][11030] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-19 02:39:41,129 [salt.state       :1951][INFO    ][11030] Completed state [192.168.11.0/24] at time 02:39:41.129655 duration_in_ms=237.665
2019-03-19 02:39:41,131 [salt.state       :1780][INFO    ][11030] Running state [maas_create_iprange_1] at time 02:39:41.131016
2019-03-19 02:39:41,131 [salt.state       :1813][INFO    ][11030] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-19 02:39:41,183 [salt.state       :300 ][INFO    ][11030] Iprange maas_create_iprange_1 already exist.
2019-03-19 02:39:41,183 [salt.state       :1951][INFO    ][11030] Completed state [maas_create_iprange_1] at time 02:39:41.183436 duration_in_ms=52.42
2019-03-19 02:39:41,183 [salt.state       :1780][INFO    ][11030] Running state [vlan 0] at time 02:39:41.183878
2019-03-19 02:39:41,184 [salt.state       :1813][INFO    ][11030] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-19 02:39:41,222 [salt.loaded.ext.module.maasng:945 ][INFO    ][11030] [{u'id': 0, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'psqknd', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-19 02:39:41,281 [salt.loaded.ext.module.maasng:945 ][INFO    ][11030] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'psqknd', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-19 02:39:41,473 [salt.loaded.ext.module.maasng:945 ][INFO    ][11030] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'psqknd', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-19 02:39:41,564 [salt.state       :300 ][INFO    ][11030] {'new': 'Vlan untagged was updated'}
2019-03-19 02:39:41,565 [salt.state       :1951][INFO    ][11030] Completed state [vlan 0] at time 02:39:41.565020 duration_in_ms=381.14
2019-03-19 02:39:41,566 [salt.state       :1780][INFO    ][11030] Running state [opnfv] at time 02:39:41.566220
2019-03-19 02:39:41,566 [salt.state       :1813][INFO    ][11030] Executing state maasng.sshkey_present for [opnfv]
2019-03-19 02:39:41,611 [salt.loaded.ext.module.maasng:1903][INFO    ][11030] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-03-19 02:39:41,612 [salt.state       :300 ][INFO    ][11030] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-03-19 02:39:41,612 [salt.state       :1951][INFO    ][11030] Completed state [opnfv] at time 02:39:41.612277 duration_in_ms=46.058
2019-03-19 02:39:41,615 [salt.minion      :1711][INFO    ][11030] Returning information for job: 20190319023914039577
2019-03-19 02:39:42,088 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319023942072900
2019-03-19 02:39:42,109 [salt.minion      :1432][INFO    ][11494] Starting a new job with PID 11494
2019-03-19 02:39:45,550 [salt.state       :915 ][INFO    ][11494] Loading fresh modules for state activity
2019-03-19 02:39:45,637 [salt.state       :1780][INFO    ][11494] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:39:45.637518
2019-03-19 02:39:45,637 [salt.state       :1813][INFO    ][11494] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-19 02:39:45,639 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11494] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-19 02:39:47,099 [salt.state       :300 ][INFO    ][11494] {'pid': 11517, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-19 02:39:47,100 [salt.state       :1951][INFO    ][11494] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:39:47.100250 duration_in_ms=1462.732
2019-03-19 02:39:47,101 [salt.state       :1780][INFO    ][11494] Running state [maas.process_machines] at time 02:39:47.101391
2019-03-19 02:39:47,101 [salt.state       :1813][INFO    ][11494] Executing state module.run for [maas.process_machines]
2019-03-19 02:39:47,101 [salt.utils.decorators:613 ][WARNING ][11494] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:39:47,804 [salt.loaded.ext.module.maas:412 ][WARNING ][11494] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-19 02:39:47,805 [salt.loaded.ext.module.maas:92  ][INFO    ][11494] machine hostname=cmp002 power_type=ipmi mac_addresses=['00:25:b5:a0:00:6a'] power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus system_id=pyhm6g architecture=amd64/generic power_parameters_power_user=admin
2019-03-19 02:39:49,002 [salt.loaded.ext.module.maas:412 ][WARNING ][11494] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-19 02:39:49,003 [salt.loaded.ext.module.maas:92  ][INFO    ][11494] machine hostname=cmp001 power_type=ipmi mac_addresses=['00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=pkdbey architecture=amd64/generic power_parameters_power_user=admin
2019-03-19 02:39:50,007 [salt.loaded.ext.module.maas:412 ][WARNING ][11494] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-19 02:39:50,008 [salt.loaded.ext.module.maas:92  ][INFO    ][11494] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=ecggd3 architecture=amd64/generic power_parameters_power_user=admin
2019-03-19 02:39:50,995 [salt.loaded.ext.module.maas:412 ][WARNING ][11494] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-19 02:39:50,996 [salt.loaded.ext.module.maas:92  ][INFO    ][11494] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=qyxqm6 architecture=amd64/generic power_parameters_power_user=admin
2019-03-19 02:39:52,261 [salt.loaded.ext.module.maas:412 ][WARNING ][11494] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-19 02:39:52,262 [salt.loaded.ext.module.maas:92  ][INFO    ][11494] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=hpsmxe architecture=amd64/generic power_parameters_power_user=admin
2019-03-19 02:39:53,541 [salt.state       :300 ][INFO    ][11494] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-19 02:39:53,542 [salt.state       :1951][INFO    ][11494] Completed state [maas.process_machines] at time 02:39:53.542001 duration_in_ms=6440.608
2019-03-19 02:39:53,546 [salt.minion      :1711][INFO    ][11494] Returning information for job: 20190319023942072900
2019-03-19 02:40:26,652 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319024026638939
2019-03-19 02:40:26,677 [salt.minion      :1432][INFO    ][11789] Starting a new job with PID 11789
2019-03-19 02:40:30,230 [salt.state       :915 ][INFO    ][11789] Loading fresh modules for state activity
2019-03-19 02:40:30,278 [salt.state       :1780][INFO    ][11789] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:40:30.278818
2019-03-19 02:40:30,279 [salt.state       :1813][INFO    ][11789] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-19 02:40:30,280 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11789] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-19 02:40:31,702 [salt.state       :300 ][INFO    ][11789] {'pid': 11796, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-19 02:40:31,703 [salt.state       :1951][INFO    ][11789] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:40:31.703268 duration_in_ms=1424.451
2019-03-19 02:40:31,704 [salt.state       :1780][INFO    ][11789] Running state [maas.wait_for_machine_status] at time 02:40:31.704471
2019-03-19 02:40:31,704 [salt.state       :1813][INFO    ][11789] Executing state module.run for [maas.wait_for_machine_status]
2019-03-19 02:40:31,705 [salt.utils.decorators:613 ][WARNING ][11789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:40:35,050 [salt.loaded.ext.module.maas:993 ][INFO    ][11789] Machine hpsmxe mark broken
2019-03-19 02:40:35,667 [salt.loaded.ext.module.maas:996 ][INFO    ][11789] Machine hpsmxe mark fixed
2019-03-19 02:40:36,881 [salt.loaded.ext.module.maas:684 ][INFO    ][11789] deploymachines hwe_kernel=ga-16.04 system_id=hpsmxe distro_series=xenial
2019-03-19 02:40:39,471 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1492.23857617s left)
2019-03-19 02:40:41,748 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024041736217
2019-03-19 02:40:41,771 [salt.minion      :1432][INFO    ][11869] Starting a new job with PID 11869
2019-03-19 02:40:41,793 [salt.minion      :1711][INFO    ][11869] Returning information for job: 20190319024041736217
2019-03-19 02:41:11,984 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024111922523
2019-03-19 02:41:12,006 [salt.minion      :1432][INFO    ][11919] Starting a new job with PID 11919
2019-03-19 02:41:12,026 [salt.minion      :1711][INFO    ][11919] Returning information for job: 20190319024111922523
2019-03-19 02:41:12,936 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1458.77294111s left)
2019-03-19 02:41:42,077 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024142065050
2019-03-19 02:41:42,102 [salt.minion      :1432][INFO    ][11950] Starting a new job with PID 11950
2019-03-19 02:41:42,123 [salt.minion      :1711][INFO    ][11950] Returning information for job: 20190319024142065050
2019-03-19 02:41:46,254 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.45518017s left)
2019-03-19 02:42:12,123 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024212111567
2019-03-19 02:42:12,149 [salt.minion      :1432][INFO    ][11991] Starting a new job with PID 11991
2019-03-19 02:42:12,172 [salt.minion      :1711][INFO    ][11991] Returning information for job: 20190319024212111567
2019-03-19 02:42:19,653 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1392.05648398s left)
2019-03-19 02:42:42,178 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024242162118
2019-03-19 02:42:42,206 [salt.minion      :1432][INFO    ][12070] Starting a new job with PID 12070
2019-03-19 02:42:42,230 [salt.minion      :1711][INFO    ][12070] Returning information for job: 20190319024242162118
2019-03-19 02:42:52,768 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1358.94069314s left)
2019-03-19 02:43:12,241 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024312227597
2019-03-19 02:43:12,265 [salt.minion      :1432][INFO    ][12164] Starting a new job with PID 12164
2019-03-19 02:43:12,290 [salt.minion      :1711][INFO    ][12164] Returning information for job: 20190319024312227597
2019-03-19 02:43:26,436 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1325.27359915s left)
2019-03-19 02:43:42,305 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024342293176
2019-03-19 02:43:42,331 [salt.minion      :1432][INFO    ][12221] Starting a new job with PID 12221
2019-03-19 02:43:42,356 [salt.minion      :1711][INFO    ][12221] Returning information for job: 20190319024342293176
2019-03-19 02:43:59,680 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1292.0291431s left)
2019-03-19 02:44:12,376 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024412364481
2019-03-19 02:44:12,400 [salt.minion      :1432][INFO    ][12324] Starting a new job with PID 12324
2019-03-19 02:44:12,426 [salt.minion      :1711][INFO    ][12324] Returning information for job: 20190319024412364481
2019-03-19 02:44:33,256 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1258.45331907s left)
2019-03-19 02:44:42,452 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024442439295
2019-03-19 02:44:42,477 [salt.minion      :1432][INFO    ][12384] Starting a new job with PID 12384
2019-03-19 02:44:42,500 [salt.minion      :1711][INFO    ][12384] Returning information for job: 20190319024442439295
2019-03-19 02:45:06,632 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1225.07700801s left)
2019-03-19 02:45:12,522 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024512509312
2019-03-19 02:45:12,547 [salt.minion      :1432][INFO    ][12454] Starting a new job with PID 12454
2019-03-19 02:45:12,570 [salt.minion      :1711][INFO    ][12454] Returning information for job: 20190319024512509312
2019-03-19 02:45:40,153 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1191.55575204s left)
2019-03-19 02:45:42,601 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024542587864
2019-03-19 02:45:42,626 [salt.minion      :1432][INFO    ][12497] Starting a new job with PID 12497
2019-03-19 02:45:42,649 [salt.minion      :1711][INFO    ][12497] Returning information for job: 20190319024542587864
2019-03-19 02:46:12,676 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024612664068
2019-03-19 02:46:12,700 [salt.minion      :1432][INFO    ][12573] Starting a new job with PID 12573
2019-03-19 02:46:12,728 [salt.minion      :1711][INFO    ][12573] Returning information for job: 20190319024612664068
2019-03-19 02:46:13,623 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1158.08663917s left)
2019-03-19 02:46:42,762 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024642749309
2019-03-19 02:46:42,787 [salt.minion      :1432][INFO    ][12611] Starting a new job with PID 12611
2019-03-19 02:46:42,811 [salt.minion      :1711][INFO    ][12611] Returning information for job: 20190319024642749309
2019-03-19 02:46:47,251 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1124.45856905s left)
2019-03-19 02:47:12,853 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024712840556
2019-03-19 02:47:12,879 [salt.minion      :1432][INFO    ][12714] Starting a new job with PID 12714
2019-03-19 02:47:12,906 [salt.minion      :1711][INFO    ][12714] Returning information for job: 20190319024712840556
2019-03-19 02:47:20,346 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1091.36298609s left)
2019-03-19 02:47:42,954 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024742941777
2019-03-19 02:47:42,978 [salt.minion      :1432][INFO    ][12762] Starting a new job with PID 12762
2019-03-19 02:47:43,002 [salt.minion      :1711][INFO    ][12762] Returning information for job: 20190319024742941777
2019-03-19 02:47:53,959 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1057.75048804s left)
2019-03-19 02:48:13,056 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024813044356
2019-03-19 02:48:13,078 [salt.minion      :1432][INFO    ][12805] Starting a new job with PID 12805
2019-03-19 02:48:13,103 [salt.minion      :1711][INFO    ][12805] Returning information for job: 20190319024813044356
2019-03-19 02:48:27,456 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1024.25319099s left)
2019-03-19 02:48:43,166 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024843153657
2019-03-19 02:48:43,191 [salt.minion      :1432][INFO    ][12842] Starting a new job with PID 12842
2019-03-19 02:48:43,216 [salt.minion      :1711][INFO    ][12842] Returning information for job: 20190319024843153657
2019-03-19 02:49:01,120 [salt.loaded.ext.module.maas:1023][INFO    ][11789] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (990.588768005s left)
2019-03-19 02:49:13,271 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command saltutil.find_job with jid 20190319024913258424
2019-03-19 02:49:13,295 [salt.minion      :1432][INFO    ][12958] Starting a new job with PID 12958
2019-03-19 02:49:13,322 [salt.minion      :1711][INFO    ][12958] Returning information for job: 20190319024913258424
2019-03-19 02:49:34,527 [salt.state       :300 ][INFO    ][11789] {'ret': True}
2019-03-19 02:49:34,528 [salt.state       :1951][INFO    ][11789] Completed state [maas.wait_for_machine_status] at time 02:49:34.528220 duration_in_ms=542823.745
2019-03-19 02:49:34,532 [salt.minion      :1711][INFO    ][11789] Returning information for job: 20190319024026638939
2019-03-19 02:49:35,183 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319024935169213
2019-03-19 02:49:35,207 [salt.minion      :1432][INFO    ][13037] Starting a new job with PID 13037
2019-03-19 02:49:38,784 [salt.state       :915 ][INFO    ][13037] Loading fresh modules for state activity
2019-03-19 02:49:38,925 [salt.state       :1780][INFO    ][13037] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:49:38.924987
2019-03-19 02:49:38,925 [salt.state       :1813][INFO    ][13037] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-19 02:49:38,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13037] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-19 02:49:40,486 [salt.state       :300 ][INFO    ][13037] {'pid': 13044, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-19 02:49:40,487 [salt.state       :1951][INFO    ][13037] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:49:40.487334 duration_in_ms=1562.347
2019-03-19 02:49:40,490 [salt.state       :1780][INFO    ][13037] Running state [maas_machines_storage_cmp002_lvm] at time 02:49:40.490568
2019-03-19 02:49:40,491 [salt.state       :1813][INFO    ][13037] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-19 02:49:41,187 [salt.state       :300 ][INFO    ][13037] Machine cmp002 is not in Ready state.
2019-03-19 02:49:41,187 [salt.state       :1951][INFO    ][13037] Completed state [maas_machines_storage_cmp002_lvm] at time 02:49:41.187453 duration_in_ms=696.885
2019-03-19 02:49:41,188 [salt.state       :1780][INFO    ][13037] Running state [maas_machines_storage_cmp001_lvm] at time 02:49:41.188021
2019-03-19 02:49:41,188 [salt.state       :1813][INFO    ][13037] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-19 02:49:41,930 [salt.state       :300 ][INFO    ][13037] Machine cmp001 is not in Ready state.
2019-03-19 02:49:41,930 [salt.state       :1951][INFO    ][13037] Completed state [maas_machines_storage_cmp001_lvm] at time 02:49:41.930713 duration_in_ms=742.691
2019-03-19 02:49:41,934 [salt.minion      :1711][INFO    ][13037] Returning information for job: 20190319024935169213
2019-03-19 02:49:42,542 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319024942533080
2019-03-19 02:49:42,567 [salt.minion      :1432][INFO    ][13055] Starting a new job with PID 13055
2019-03-19 02:49:43,167 [salt.state       :915 ][INFO    ][13055] Loading fresh modules for state activity
2019-03-19 02:49:43,215 [salt.state       :1780][INFO    ][13055] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:49:43.215317
2019-03-19 02:49:43,215 [salt.state       :1813][INFO    ][13055] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-19 02:49:43,216 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13055] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-19 02:49:44,696 [salt.state       :300 ][INFO    ][13055] {'pid': 13062, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-19 02:49:44,696 [salt.state       :1951][INFO    ][13055] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:49:44.696421 duration_in_ms=1481.105
2019-03-19 02:49:44,697 [salt.state       :1780][INFO    ][13055] Running state [maas.deploy_machines] at time 02:49:44.697632
2019-03-19 02:49:44,697 [salt.state       :1813][INFO    ][13055] Executing state module.run for [maas.deploy_machines]
2019-03-19 02:49:44,698 [salt.utils.decorators:613 ][WARNING ][13055] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:49:45,415 [salt.state       :300 ][INFO    ][13055] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-19 02:49:45,415 [salt.state       :1951][INFO    ][13055] Completed state [maas.deploy_machines] at time 02:49:45.415776 duration_in_ms=718.141
2019-03-19 02:49:45,420 [salt.minion      :1711][INFO    ][13055] Returning information for job: 20190319024942533080
2019-03-19 02:49:46,053 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command state.apply with jid 20190319024946040631
2019-03-19 02:49:46,078 [salt.minion      :1432][INFO    ][13071] Starting a new job with PID 13071
2019-03-19 02:49:46,699 [salt.state       :915 ][INFO    ][13071] Loading fresh modules for state activity
2019-03-19 02:49:46,784 [salt.state       :1780][INFO    ][13071] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:49:46.784530
2019-03-19 02:49:46,784 [salt.state       :1813][INFO    ][13071] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-19 02:49:46,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13071] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-19 02:49:48,205 [salt.state       :300 ][INFO    ][13071] {'pid': 13078, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-19 02:49:48,206 [salt.state       :1951][INFO    ][13071] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:49:48.205992 duration_in_ms=1421.463
2019-03-19 02:49:48,207 [salt.state       :1780][INFO    ][13071] Running state [maas.wait_for_machine_status] at time 02:49:48.207132
2019-03-19 02:49:48,207 [salt.state       :1813][INFO    ][13071] Executing state module.run for [maas.wait_for_machine_status]
2019-03-19 02:49:48,207 [salt.utils.decorators:613 ][WARNING ][13071] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-19 02:49:51,533 [salt.state       :300 ][INFO    ][13071] {'ret': True}
2019-03-19 02:49:51,534 [salt.state       :1951][INFO    ][13071] Completed state [maas.wait_for_machine_status] at time 02:49:51.533948 duration_in_ms=3326.812
2019-03-19 02:49:51,537 [salt.minion      :1711][INFO    ][13071] Returning information for job: 20190319024946040631
2019-03-19 03:15:01,316 [salt.utils.schedule:1377][INFO    ][3179] Running scheduled job: __mine_interval
2019-03-19 04:15:01,315 [salt.utils.schedule:1377][INFO    ][3179] Running scheduled job: __mine_interval
2019-03-19 04:44:37,018 [salt.minion      :1308][INFO    ][3179] User sudo_ubuntu Executing command cp.push_dir with jid 20190319044437006223
2019-03-19 04:44:37,038 [salt.minion      :1432][INFO    ][21183] Starting a new job with PID 21183
