2019-05-23 20:10:24,479 [salt.utils.decorators:613 ][WARNING ][2135] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:10:25,446 [salt.utils.decorators:613 ][WARNING ][2135] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:10:28,576 [salt.loaded.int.states.file:2298][WARNING ][2466] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-23 20:10:55,139 [salt.state       :2022][WARNING ][2888] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-23 20:10:57,343 [salt.utils.decorators:613 ][WARNING ][2888] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:11:07,870 [salt.utils.decorators:613 ][WARNING ][2888] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:11:35,111 [salt.utils.decorators:613 ][WARNING ][2888] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:11:36,143 [salt.utils.decorators:613 ][WARNING ][2888] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:11:38,088 [salt.loaded.ext.module.maasng:1008][WARNING ][2888] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-23 20:11:38,089 [salt.loaded.ext.module.maasng:1011][WARNING ][2888] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-23 20:11:38,814 [salt.loaded.ext.module.maasng:1235][WARNING ][2888] Ignoring parameter vlan:0
2019-05-23 20:11:42,504 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-23 20:11:42,536 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-23 20:11:42,575 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-05-23 20:11:43,624 [salt.cli.daemons :293 ][INFO    ][5118] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-05-23 20:11:43,747 [salt.cli.daemons :82  ][INFO    ][5118] Starting up the Salt Minion
2019-05-23 20:11:43,748 [salt.utils.event :1017][INFO    ][5118] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-05-23 20:11:45,021 [salt.minion      :976 ][INFO    ][5118] Creating minion process manager
2019-05-23 20:11:47,001 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5118] Executing command ['date', '+%z'] in directory '/root'
2019-05-23 20:11:47,025 [salt.utils.schedule:568 ][INFO    ][5118] Updating job settings for scheduled job: __mine_interval
2019-05-23 20:11:47,027 [salt.minion      :1108][INFO    ][5118] Added mine.update to scheduler
2019-05-23 20:11:47,035 [salt.minion      :1975][INFO    ][5118] Minion is starting as user 'root'
2019-05-23 20:11:47,051 [salt.minion      :2336][INFO    ][5118] Minion is ready to receive requests!
2019-05-23 20:11:48,883 [salt.utils.decorators:613 ][WARNING ][5048] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:11:48,950 [salt.loaded.ext.module.maas:412 ][WARNING ][5048] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 20:11:50,671 [salt.loaded.ext.module.maas:412 ][WARNING ][5048] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 20:11:52,102 [salt.loaded.ext.module.maas:412 ][WARNING ][5048] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 20:11:53,444 [salt.loaded.ext.module.maas:412 ][WARNING ][5048] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 20:11:54,813 [salt.loaded.ext.module.maas:412 ][WARNING ][5048] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 20:11:55,398 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201155387564
2019-05-23 20:11:55,422 [salt.minion      :1432][INFO    ][5464] Starting a new job with PID 5464
2019-05-23 20:11:55,451 [salt.minion      :1711][INFO    ][5464] Returning information for job: 20190523201155387564
2019-05-23 20:12:27,532 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523201227514942
2019-05-23 20:12:27,559 [salt.minion      :1432][INFO    ][5535] Starting a new job with PID 5535
2019-05-23 20:12:33,564 [salt.state       :915 ][INFO    ][5535] Loading fresh modules for state activity
2019-05-23 20:12:33,628 [salt.fileclient  :1219][INFO    ][5535] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-23 20:12:33,693 [salt.state       :1780][INFO    ][5535] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:12:33.693014
2019-05-23 20:12:33,693 [salt.state       :1813][INFO    ][5535] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 20:12:33,695 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5535] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 20:12:35,540 [salt.state       :300 ][INFO    ][5535] {'pid': 5546, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 20:12:35,542 [salt.state       :1951][INFO    ][5535] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:12:35.542348 duration_in_ms=1849.335
2019-05-23 20:12:35,545 [salt.state       :1780][INFO    ][5535] Running state [maas.wait_for_machine_status] at time 20:12:35.545480
2019-05-23 20:12:35,547 [salt.state       :1813][INFO    ][5535] Executing state module.run for [maas.wait_for_machine_status]
2019-05-23 20:12:35,548 [salt.utils.decorators:613 ][WARNING ][5535] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:12:36,420 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.13872194s left)
2019-05-23 20:12:42,641 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201242626183
2019-05-23 20:12:42,669 [salt.minion      :1432][INFO    ][5557] Starting a new job with PID 5557
2019-05-23 20:12:42,698 [salt.minion      :1711][INFO    ][5557] Returning information for job: 20190523201242626183
2019-05-23 20:13:07,298 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.26162386s left)
2019-05-23 20:13:12,736 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201312718853
2019-05-23 20:13:12,763 [salt.minion      :1432][INFO    ][5609] Starting a new job with PID 5609
2019-05-23 20:13:12,796 [salt.minion      :1711][INFO    ][5609] Returning information for job: 20190523201312718853
2019-05-23 20:13:38,159 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.39947581s left)
2019-05-23 20:13:42,836 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201342819786
2019-05-23 20:13:42,864 [salt.minion      :1432][INFO    ][5629] Starting a new job with PID 5629
2019-05-23 20:13:42,889 [salt.minion      :1711][INFO    ][5629] Returning information for job: 20190523201342819786
2019-05-23 20:14:08,913 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.64577103s left)
2019-05-23 20:14:12,928 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201412911149
2019-05-23 20:14:12,955 [salt.minion      :1432][INFO    ][5688] Starting a new job with PID 5688
2019-05-23 20:14:12,985 [salt.minion      :1711][INFO    ][5688] Returning information for job: 20190523201412911149
2019-05-23 20:14:39,984 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.57466388s left)
2019-05-23 20:14:43,029 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201443018246
2019-05-23 20:14:43,054 [salt.minion      :1432][INFO    ][5739] Starting a new job with PID 5739
2019-05-23 20:14:43,081 [salt.minion      :1711][INFO    ][5739] Returning information for job: 20190523201443018246
2019-05-23 20:15:11,077 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.48233199s left)
2019-05-23 20:15:13,170 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201513155551
2019-05-23 20:15:13,194 [salt.minion      :1432][INFO    ][5929] Starting a new job with PID 5929
2019-05-23 20:15:13,223 [salt.minion      :1711][INFO    ][5929] Returning information for job: 20190523201513155551
2019-05-23 20:15:42,225 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.33390784s left)
2019-05-23 20:15:43,279 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201543264630
2019-05-23 20:15:43,305 [salt.minion      :1432][INFO    ][5994] Starting a new job with PID 5994
2019-05-23 20:15:43,335 [salt.minion      :1711][INFO    ][5994] Returning information for job: 20190523201543264630
2019-05-23 20:16:13,400 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201613384241
2019-05-23 20:16:13,421 [salt.minion      :1432][INFO    ][6293] Starting a new job with PID 6293
2019-05-23 20:16:13,447 [salt.minion      :1711][INFO    ][6293] Returning information for job: 20190523201613384241
2019-05-23 20:16:13,580 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.97870588s left)
2019-05-23 20:16:43,508 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201643493430
2019-05-23 20:16:43,531 [salt.minion      :1432][INFO    ][6426] Starting a new job with PID 6426
2019-05-23 20:16:43,560 [salt.minion      :1711][INFO    ][6426] Returning information for job: 20190523201643493430
2019-05-23 20:16:45,375 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.18370986s left)
2019-05-23 20:17:13,687 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201713669216
2019-05-23 20:17:13,713 [salt.minion      :1432][INFO    ][6765] Starting a new job with PID 6765
2019-05-23 20:17:13,740 [salt.minion      :1711][INFO    ][6765] Returning information for job: 20190523201713669216
2019-05-23 20:17:17,736 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.82262087s left)
2019-05-23 20:17:43,807 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201743795003
2019-05-23 20:17:43,832 [salt.minion      :1432][INFO    ][6913] Starting a new job with PID 6913
2019-05-23 20:17:43,864 [salt.minion      :1711][INFO    ][6913] Returning information for job: 20190523201743795003
2019-05-23 20:17:50,018 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.54064798s left)
2019-05-23 20:18:13,963 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201813949647
2019-05-23 20:18:13,987 [salt.minion      :1432][INFO    ][7146] Starting a new job with PID 7146
2019-05-23 20:18:14,014 [salt.minion      :1711][INFO    ][7146] Returning information for job: 20190523201813949647
2019-05-23 20:18:22,184 [salt.loaded.ext.module.maas:1023][INFO    ][5535] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.37502599s left)
2019-05-23 20:18:44,089 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201844073052
2019-05-23 20:18:44,117 [salt.minion      :1432][INFO    ][7290] Starting a new job with PID 7290
2019-05-23 20:18:44,145 [salt.minion      :1711][INFO    ][7290] Returning information for job: 20190523201844073052
2019-05-23 20:18:55,100 [salt.state       :300 ][INFO    ][5535] {'ret': True}
2019-05-23 20:18:55,100 [salt.state       :1951][INFO    ][5535] Completed state [maas.wait_for_machine_status] at time 20:18:55.100834 duration_in_ms=379555.353
2019-05-23 20:18:55,105 [salt.minion      :1711][INFO    ][5535] Returning information for job: 20190523201227514942
2019-05-23 20:18:55,808 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523201855798730
2019-05-23 20:18:55,834 [salt.minion      :1432][INFO    ][7323] Starting a new job with PID 7323
2019-05-23 20:19:02,147 [salt.state       :915 ][INFO    ][7323] Loading fresh modules for state activity
2019-05-23 20:19:02,212 [salt.fileclient  :1219][INFO    ][7323] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-23 20:19:02,324 [salt.state       :1780][INFO    ][7323] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:02.324555
2019-05-23 20:19:02,324 [salt.state       :1813][INFO    ][7323] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 20:19:02,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7323] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 20:19:04,156 [salt.state       :300 ][INFO    ][7323] {'pid': 7417, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 20:19:04,157 [salt.state       :1951][INFO    ][7323] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:04.157475 duration_in_ms=1832.92
2019-05-23 20:19:04,162 [salt.state       :1780][INFO    ][7323] Running state [maas_machines_storage_cmp002_lvm] at time 20:19:04.162125
2019-05-23 20:19:04,162 [salt.state       :1813][INFO    ][7323] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-23 20:19:05,351 [salt.loaded.ext.module.maasng:610 ][INFO    ][7323] y4dhds
2019-05-23 20:19:05,352 [salt.loaded.ext.module.maasng:626 ][INFO    ][7323] sda
2019-05-23 20:19:05,913 [salt.loaded.ext.module.maasng:361 ][INFO    ][7323] y4dhds
2019-05-23 20:19:06,004 [salt.loaded.ext.module.maasng:367 ][INFO    ][7323] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/blockdevices/1/', u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'system_id': u'y4dhds', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'3235ebdb-b2ea-4f52-9035-7e04db76cd4a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y4dhds', u'device_id': 1, u'filesystem': {u'uuid': u'a231a260-f98a-42e0-8ff0-9cba2e3c9126', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'uuid': u'4465154c-a11d-4647-a0c6-9a673988dee2', u'name': u'vgroot-lvroot', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/blockdevices/3/', u'filesystem': {u'uuid': u'87d83c80-fac9-43a2-93bc-441de4565ffb', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'system_id': u'y4dhds', 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'id': 3, u'size': 800097042432}]
2019-05-23 20:19:06,005 [salt.loaded.ext.module.maasng:632 ][INFO    ][7323] vgroot
2019-05-23 20:19:06,005 [salt.loaded.ext.module.maasng:635 ][INFO    ][7323] lvroot
2019-05-23 20:19:06,006 [salt.loaded.ext.module.maasng:639 ][INFO    ][7323] 107374182400
2019-05-23 20:19:06,656 [salt.loaded.ext.module.maasng:645 ][INFO    ][7323] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'y4dhds', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'b96013d5-b60f-4025-a42d-85ea77b36afb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y4dhds', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'd5d5de4e-5d58-4318-afb3-ae33f3bfe22a', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_size': 800106479616, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/blockdevices/1/'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'f2067705-d22b-4459-9d18-4aa7e98ee84c', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'y4dhds', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'f9d102ca-1048-4038-9aaa-4abcebe58dcf', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 107374182400, u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/blockdevices/11/'}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'y4dhds', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'f2067705-d22b-4459-9d18-4aa7e98ee84c', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'id': 11, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'y4dhds', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'f9d102ca-1048-4038-9aaa-4abcebe58dcf', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/blockdevices/11/'}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'y4dhds', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_interface': {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': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'y4dhds', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/interfaces/4/'}, 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'size': 800109715456, u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'y4dhds', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'b96013d5-b60f-4025-a42d-85ea77b36afb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y4dhds', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'd5d5de4e-5d58-4318-afb3-ae33f3bfe22a', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/blockdevices/1/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'y4dhds', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'b96013d5-b60f-4025-a42d-85ea77b36afb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y4dhds', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'd5d5de4e-5d58-4318-afb3-ae33f3bfe22a', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/blockdevices/1/'}, u'interface_set': [{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': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'y4dhds', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/interfaces/4/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'y4dhds', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/interfaces/14/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'y4dhds', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/interfaces/15/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'y4dhds', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/interfaces/13/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'y4dhds', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/interfaces/11/'}, {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'y4dhds', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/y4dhds/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/y4dhds/'}
2019-05-23 20:19:06,659 [salt.state       :300 ][INFO    ][7323] {'new': {'storage_layout': 'lvm'}}
2019-05-23 20:19:06,660 [salt.state       :1951][INFO    ][7323] Completed state [maas_machines_storage_cmp002_lvm] at time 20:19:06.660020 duration_in_ms=2497.893
2019-05-23 20:19:06,660 [salt.state       :1780][INFO    ][7323] Running state [maas_machines_storage_cmp001_lvm] at time 20:19:06.660798
2019-05-23 20:19:06,661 [salt.state       :1813][INFO    ][7323] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-23 20:19:07,968 [salt.loaded.ext.module.maasng:610 ][INFO    ][7323] gh8w3r
2019-05-23 20:19:07,968 [salt.loaded.ext.module.maasng:626 ][INFO    ][7323] sda
2019-05-23 20:19:08,560 [salt.loaded.ext.module.maasng:361 ][INFO    ][7323] gh8w3r
2019-05-23 20:19:08,658 [salt.loaded.ext.module.maasng:367 ][INFO    ][7323] [{u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'20c78fc2-50cc-497e-af76-6a9f5194d523', u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gh8w3r', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'4d0da322-0861-40aa-a76c-0ddebd7a54db', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'gh8w3r', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/blockdevices/2/'}, {u'block_size': 4096, u'uuid': u'87632dcb-21bb-4088-81d0-31b4b14480f2', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'partitions': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'gh8w3r', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'23646024-ef54-48cf-9022-9a210265cea1', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 800097042432, u'type': u'virtual', u'id': 4, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/blockdevices/4/'}]
2019-05-23 20:19:08,659 [salt.loaded.ext.module.maasng:632 ][INFO    ][7323] vgroot
2019-05-23 20:19:08,659 [salt.loaded.ext.module.maasng:635 ][INFO    ][7323] lvroot
2019-05-23 20:19:08,659 [salt.loaded.ext.module.maasng:639 ][INFO    ][7323] 107374182400
2019-05-23 20:19:09,304 [salt.loaded.ext.module.maasng:645 ][INFO    ][7323] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'xetsmh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'xetsmh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'xetsmh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'gh8w3r', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'gh8w3r', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'f3906689-432c-4cab-aa55-445e08b5f4ac', u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gh8w3r', u'filesystem': {u'label': None, u'uuid': u'0e9c245c-3d6f-41a8-98f4-0439f4bca6bc', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'gh8w3r', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'gh8w3r', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'f3906689-432c-4cab-aa55-445e08b5f4ac', u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gh8w3r', u'filesystem': {u'label': None, u'uuid': u'0e9c245c-3d6f-41a8-98f4-0439f4bca6bc', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'663ac1e1-9195-4e46-bc55-09b320e41bb4', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'gh8w3r', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'c2722281-e461-4662-be6c-1251da09187e', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'gh8w3r', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'f3906689-432c-4cab-aa55-445e08b5f4ac', u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gh8w3r', u'filesystem': {u'label': None, u'uuid': u'0e9c245c-3d6f-41a8-98f4-0439f4bca6bc', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'gh8w3r', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'disable_ipv4': False, u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'663ac1e1-9195-4e46-bc55-09b320e41bb4', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'gh8w3r', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'c2722281-e461-4662-be6c-1251da09187e', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'xetsmh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'xetsmh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'xetsmh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'gh8w3r', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'gh8w3r', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/interfaces/17/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'gh8w3r', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/interfaces/18/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'xetsmh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'xetsmh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'xetsmh', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.43'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'gh8w3r', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/interfaces/16/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'gh8w3r', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 28}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'gh8w3r', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/gh8w3r/interfaces/20/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/gh8w3r/', u'distro_series': u'', u'memory_test_status': -1}
2019-05-23 20:19:09,307 [salt.state       :300 ][INFO    ][7323] {'new': {'storage_layout': 'lvm'}}
2019-05-23 20:19:09,308 [salt.state       :1951][INFO    ][7323] Completed state [maas_machines_storage_cmp001_lvm] at time 20:19:09.308168 duration_in_ms=2647.37
2019-05-23 20:19:09,312 [salt.minion      :1711][INFO    ][7323] Returning information for job: 20190523201855798730
2019-05-23 20:19:10,055 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523201910040881
2019-05-23 20:19:10,076 [salt.minion      :1432][INFO    ][7441] Starting a new job with PID 7441
2019-05-23 20:19:11,048 [salt.state       :915 ][INFO    ][7441] Loading fresh modules for state activity
2019-05-23 20:19:11,116 [salt.fileclient  :1219][INFO    ][7441] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-23 20:19:11,169 [salt.state       :1780][INFO    ][7441] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:11.169561
2019-05-23 20:19:11,169 [salt.state       :1813][INFO    ][7441] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 20:19:11,171 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7441] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 20:19:13,112 [salt.state       :300 ][INFO    ][7441] {'pid': 7448, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 20:19:13,113 [salt.state       :1951][INFO    ][7441] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:13.113656 duration_in_ms=1944.094
2019-05-23 20:19:13,117 [salt.state       :1780][INFO    ][7441] Running state [maas.deploy_machines] at time 20:19:13.116929
2019-05-23 20:19:13,117 [salt.state       :1813][INFO    ][7441] Executing state module.run for [maas.deploy_machines]
2019-05-23 20:19:13,119 [salt.utils.decorators:613 ][WARNING ][7441] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:19:13,684 [salt.loaded.ext.module.maas:684 ][INFO    ][7441] deploymachines hwe_kernel=hwe-16.04 system_id=y4dhds distro_series=xenial
2019-05-23 20:19:16,192 [salt.loaded.ext.module.maas:684 ][INFO    ][7441] deploymachines hwe_kernel=hwe-16.04 system_id=gh8w3r distro_series=xenial
2019-05-23 20:19:18,693 [salt.loaded.ext.module.maas:684 ][INFO    ][7441] deploymachines hwe_kernel=hwe-16.04 system_id=rhcpy6 distro_series=xenial
2019-05-23 20:19:21,262 [salt.loaded.ext.module.maas:684 ][INFO    ][7441] deploymachines hwe_kernel=hwe-16.04 system_id=4tf67c distro_series=xenial
2019-05-23 20:19:23,760 [salt.loaded.ext.module.maas:684 ][INFO    ][7441] deploymachines hwe_kernel=hwe-16.04 system_id=4rknnk distro_series=xenial
2019-05-23 20:19:25,126 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201925113228
2019-05-23 20:19:25,151 [salt.minion      :1432][INFO    ][7707] Starting a new job with PID 7707
2019-05-23 20:19:25,176 [salt.minion      :1711][INFO    ][7707] Returning information for job: 20190523201925113228
2019-05-23 20:19:26,313 [salt.state       :300 ][INFO    ][7441] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-23 20:19:26,314 [salt.state       :1951][INFO    ][7441] Completed state [maas.deploy_machines] at time 20:19:26.314824 duration_in_ms=13197.895
2019-05-23 20:19:26,322 [salt.minion      :1711][INFO    ][7441] Returning information for job: 20190523201910040881
2019-05-23 20:19:27,031 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523201927021584
2019-05-23 20:19:27,059 [salt.minion      :1432][INFO    ][7724] Starting a new job with PID 7724
2019-05-23 20:19:33,091 [salt.state       :915 ][INFO    ][7724] Loading fresh modules for state activity
2019-05-23 20:19:33,162 [salt.fileclient  :1219][INFO    ][7724] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-23 20:19:33,226 [salt.state       :1780][INFO    ][7724] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:33.226547
2019-05-23 20:19:33,226 [salt.state       :1813][INFO    ][7724] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 20:19:33,229 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7724] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 20:19:35,212 [salt.state       :300 ][INFO    ][7724] {'pid': 7742, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 20:19:35,214 [salt.state       :1951][INFO    ][7724] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:35.213642 duration_in_ms=1987.095
2019-05-23 20:19:35,217 [salt.state       :1780][INFO    ][7724] Running state [maas.wait_for_machine_status] at time 20:19:35.217203
2019-05-23 20:19:35,217 [salt.state       :1813][INFO    ][7724] Executing state module.run for [maas.wait_for_machine_status]
2019-05-23 20:19:35,218 [salt.utils.decorators:613 ][WARNING ][7724] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:19:37,960 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.27027988s left)
2019-05-23 20:19:42,054 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523201942039342
2019-05-23 20:19:42,083 [salt.minion      :1432][INFO    ][7753] Starting a new job with PID 7753
2019-05-23 20:19:42,109 [salt.minion      :1711][INFO    ][7753] Returning information for job: 20190523201942039342
2019-05-23 20:20:10,988 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.24297404s left)
2019-05-23 20:20:12,153 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202012139668
2019-05-23 20:20:12,183 [salt.minion      :1432][INFO    ][7821] Starting a new job with PID 7821
2019-05-23 20:20:12,213 [salt.minion      :1711][INFO    ][7821] Returning information for job: 20190523202012139668
2019-05-23 20:20:42,236 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202042227200
2019-05-23 20:20:42,258 [salt.minion      :1432][INFO    ][7837] Starting a new job with PID 7837
2019-05-23 20:20:42,286 [salt.minion      :1711][INFO    ][7837] Returning information for job: 20190523202042227200
2019-05-23 20:20:44,028 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.20212483s left)
2019-05-23 20:21:12,318 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202112302855
2019-05-23 20:21:12,349 [salt.minion      :1432][INFO    ][7912] Starting a new job with PID 7912
2019-05-23 20:21:12,375 [salt.minion      :1711][INFO    ][7912] Returning information for job: 20190523202112302855
2019-05-23 20:21:16,952 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.27843094s left)
2019-05-23 20:21:42,404 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202142391779
2019-05-23 20:21:42,432 [salt.minion      :1432][INFO    ][7933] Starting a new job with PID 7933
2019-05-23 20:21:42,463 [salt.minion      :1711][INFO    ][7933] Returning information for job: 20190523202142391779
2019-05-23 20:21:49,929 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.30128694s left)
2019-05-23 20:22:12,497 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202212482531
2019-05-23 20:22:12,523 [salt.minion      :1432][INFO    ][8012] Starting a new job with PID 8012
2019-05-23 20:22:12,553 [salt.minion      :1711][INFO    ][8012] Returning information for job: 20190523202212482531
2019-05-23 20:22:23,012 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.21892691s left)
2019-05-23 20:22:42,599 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202242581465
2019-05-23 20:22:42,625 [salt.minion      :1432][INFO    ][8081] Starting a new job with PID 8081
2019-05-23 20:22:42,660 [salt.minion      :1711][INFO    ][8081] Returning information for job: 20190523202242581465
2019-05-23 20:22:55,937 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.29344988s left)
2019-05-23 20:23:12,754 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202312744120
2019-05-23 20:23:12,777 [salt.minion      :1432][INFO    ][8381] Starting a new job with PID 8381
2019-05-23 20:23:12,808 [salt.minion      :1711][INFO    ][8381] Returning information for job: 20190523202312744120
2019-05-23 20:23:28,861 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.36982989s left)
2019-05-23 20:23:42,846 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202342837965
2019-05-23 20:23:42,869 [salt.minion      :1432][INFO    ][8440] Starting a new job with PID 8440
2019-05-23 20:23:42,899 [salt.minion      :1711][INFO    ][8440] Returning information for job: 20190523202342837965
2019-05-23 20:24:01,879 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.35152984s left)
2019-05-23 20:24:12,964 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202412953192
2019-05-23 20:24:12,991 [salt.minion      :1432][INFO    ][8680] Starting a new job with PID 8680
2019-05-23 20:24:13,018 [salt.minion      :1711][INFO    ][8680] Returning information for job: 20190523202412953192
2019-05-23 20:24:34,834 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.39609694s left)
2019-05-23 20:24:43,110 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202443086920
2019-05-23 20:24:43,137 [salt.minion      :1432][INFO    ][8740] Starting a new job with PID 8740
2019-05-23 20:24:43,168 [salt.minion      :1711][INFO    ][8740] Returning information for job: 20190523202443086920
2019-05-23 20:25:07,757 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.47400498s left)
2019-05-23 20:25:13,318 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202513304257
2019-05-23 20:25:13,344 [salt.minion      :1432][INFO    ][9236] Starting a new job with PID 9236
2019-05-23 20:25:13,371 [salt.minion      :1711][INFO    ][9236] Returning information for job: 20190523202513304257
2019-05-23 20:25:40,691 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.53918695s left)
2019-05-23 20:25:43,462 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202543447518
2019-05-23 20:25:43,489 [salt.minion      :1432][INFO    ][9257] Starting a new job with PID 9257
2019-05-23 20:25:43,522 [salt.minion      :1711][INFO    ][9257] Returning information for job: 20190523202543447518
2019-05-23 20:26:13,656 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202613645134
2019-05-23 20:26:13,677 [salt.minion      :1432][INFO    ][9576] Starting a new job with PID 9576
2019-05-23 20:26:13,711 [salt.minion      :1711][INFO    ][9576] Returning information for job: 20190523202613645134
2019-05-23 20:26:13,868 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.36253595s left)
2019-05-23 20:26:43,809 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202643797332
2019-05-23 20:26:43,836 [salt.minion      :1432][INFO    ][9603] Starting a new job with PID 9603
2019-05-23 20:26:43,865 [salt.minion      :1711][INFO    ][9603] Returning information for job: 20190523202643797332
2019-05-23 20:26:46,969 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.26164603s left)
2019-05-23 20:27:13,999 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202713986597
2019-05-23 20:27:14,027 [salt.minion      :1432][INFO    ][9933] Starting a new job with PID 9933
2019-05-23 20:27:14,060 [salt.minion      :1711][INFO    ][9933] Returning information for job: 20190523202713986597
2019-05-23 20:27:19,894 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.33662105s left)
2019-05-23 20:27:44,170 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202744156780
2019-05-23 20:27:44,200 [salt.minion      :1432][INFO    ][9973] Starting a new job with PID 9973
2019-05-23 20:27:44,242 [salt.minion      :1711][INFO    ][9973] Returning information for job: 20190523202744156780
2019-05-23 20:27:52,782 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.44876289s left)
2019-05-23 20:28:14,384 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202814370526
2019-05-23 20:28:14,412 [salt.minion      :1432][INFO    ][10168] Starting a new job with PID 10168
2019-05-23 20:28:14,448 [salt.minion      :1711][INFO    ][10168] Returning information for job: 20190523202814370526
2019-05-23 20:28:25,653 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.57701087s left)
2019-05-23 20:28:44,406 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202844394113
2019-05-23 20:28:44,431 [salt.minion      :1432][INFO    ][10234] Starting a new job with PID 10234
2019-05-23 20:28:44,461 [salt.minion      :1711][INFO    ][10234] Returning information for job: 20190523202844394113
2019-05-23 20:28:58,763 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.46775985s left)
2019-05-23 20:29:14,590 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202914577052
2019-05-23 20:29:14,615 [salt.minion      :1432][INFO    ][10386] Starting a new job with PID 10386
2019-05-23 20:29:14,646 [salt.minion      :1711][INFO    ][10386] Returning information for job: 20190523202914577052
2019-05-23 20:29:31,708 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1653.52248096s left)
2019-05-23 20:29:44,785 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523202944771527
2019-05-23 20:29:44,814 [salt.minion      :1432][INFO    ][10444] Starting a new job with PID 10444
2019-05-23 20:29:44,851 [salt.minion      :1711][INFO    ][10444] Returning information for job: 20190523202944771527
2019-05-23 20:30:04,714 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1620.51693988s left)
2019-05-23 20:30:14,996 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203014982420
2019-05-23 20:30:15,023 [salt.minion      :1432][INFO    ][10611] Starting a new job with PID 10611
2019-05-23 20:30:15,056 [salt.minion      :1711][INFO    ][10611] Returning information for job: 20190523203014982420
2019-05-23 20:30:37,681 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1587.54966903s left)
2019-05-23 20:30:45,177 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203045166782
2019-05-23 20:30:45,202 [salt.minion      :1432][INFO    ][10772] Starting a new job with PID 10772
2019-05-23 20:30:45,240 [salt.minion      :1711][INFO    ][10772] Returning information for job: 20190523203045166782
2019-05-23 20:31:10,550 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1554.68021202s left)
2019-05-23 20:31:15,388 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203115375222
2019-05-23 20:31:15,418 [salt.minion      :1432][INFO    ][10836] Starting a new job with PID 10836
2019-05-23 20:31:15,447 [salt.minion      :1711][INFO    ][10836] Returning information for job: 20190523203115375222
2019-05-23 20:31:43,666 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1521.56485486s left)
2019-05-23 20:31:45,588 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203145580894
2019-05-23 20:31:45,610 [salt.minion      :1432][INFO    ][10940] Starting a new job with PID 10940
2019-05-23 20:31:45,636 [salt.minion      :1711][INFO    ][10940] Returning information for job: 20190523203145580894
2019-05-23 20:32:15,739 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203215726638
2019-05-23 20:32:15,763 [salt.minion      :1432][INFO    ][11224] Starting a new job with PID 11224
2019-05-23 20:32:15,791 [salt.minion      :1711][INFO    ][11224] Returning information for job: 20190523203215726638
2019-05-23 20:32:16,845 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1488.38576102s left)
2019-05-23 20:32:45,760 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203245745706
2019-05-23 20:32:45,788 [salt.minion      :1432][INFO    ][11248] Starting a new job with PID 11248
2019-05-23 20:32:45,817 [salt.minion      :1711][INFO    ][11248] Returning information for job: 20190523203245745706
2019-05-23 20:32:49,527 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1455.70327783s left)
2019-05-23 20:33:15,787 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203315775252
2019-05-23 20:33:15,814 [salt.minion      :1432][INFO    ][11328] Starting a new job with PID 11328
2019-05-23 20:33:15,839 [salt.minion      :1711][INFO    ][11328] Returning information for job: 20190523203315775252
2019-05-23 20:33:22,400 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1422.83034301s left)
2019-05-23 20:33:45,826 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203345813555
2019-05-23 20:33:45,853 [salt.minion      :1432][INFO    ][11357] Starting a new job with PID 11357
2019-05-23 20:33:45,883 [salt.minion      :1711][INFO    ][11357] Returning information for job: 20190523203345813555
2019-05-23 20:33:55,330 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1389.90041995s left)
2019-05-23 20:34:15,872 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203415858986
2019-05-23 20:34:15,896 [salt.minion      :1432][INFO    ][11404] Starting a new job with PID 11404
2019-05-23 20:34:15,923 [salt.minion      :1711][INFO    ][11404] Returning information for job: 20190523203415858986
2019-05-23 20:34:28,109 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1357.12135386s left)
2019-05-23 20:34:45,931 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203445916348
2019-05-23 20:34:45,958 [salt.minion      :1432][INFO    ][11443] Starting a new job with PID 11443
2019-05-23 20:34:45,991 [salt.minion      :1711][INFO    ][11443] Returning information for job: 20190523203445916348
2019-05-23 20:35:00,969 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1324.26128483s left)
2019-05-23 20:35:16,007 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203515990876
2019-05-23 20:35:16,035 [salt.minion      :1432][INFO    ][11491] Starting a new job with PID 11491
2019-05-23 20:35:16,062 [salt.minion      :1711][INFO    ][11491] Returning information for job: 20190523203515990876
2019-05-23 20:35:33,741 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1291.48942089s left)
2019-05-23 20:35:46,111 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203546097054
2019-05-23 20:35:46,139 [salt.minion      :1432][INFO    ][11520] Starting a new job with PID 11520
2019-05-23 20:35:46,168 [salt.minion      :1711][INFO    ][11520] Returning information for job: 20190523203546097054
2019-05-23 20:36:06,537 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1258.69363689s left)
2019-05-23 20:36:16,192 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203616176607
2019-05-23 20:36:16,223 [salt.minion      :1432][INFO    ][11575] Starting a new job with PID 11575
2019-05-23 20:36:16,251 [salt.minion      :1711][INFO    ][11575] Returning information for job: 20190523203616176607
2019-05-23 20:36:39,459 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1225.77107692s left)
2019-05-23 20:36:46,300 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203646289800
2019-05-23 20:36:46,327 [salt.minion      :1432][INFO    ][11604] Starting a new job with PID 11604
2019-05-23 20:36:46,360 [salt.minion      :1711][INFO    ][11604] Returning information for job: 20190523203646289800
2019-05-23 20:37:11,958 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1193.27207398s left)
2019-05-23 20:37:16,415 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203716402260
2019-05-23 20:37:16,443 [salt.minion      :1432][INFO    ][11651] Starting a new job with PID 11651
2019-05-23 20:37:16,472 [salt.minion      :1711][INFO    ][11651] Returning information for job: 20190523203716402260
2019-05-23 20:37:45,064 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1160.16615295s left)
2019-05-23 20:37:46,563 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203746548193
2019-05-23 20:37:46,588 [salt.minion      :1432][INFO    ][11681] Starting a new job with PID 11681
2019-05-23 20:37:46,619 [salt.minion      :1711][INFO    ][11681] Returning information for job: 20190523203746548193
2019-05-23 20:38:16,711 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203816702427
2019-05-23 20:38:16,726 [salt.minion      :1432][INFO    ][11731] Starting a new job with PID 11731
2019-05-23 20:38:16,756 [salt.minion      :1711][INFO    ][11731] Returning information for job: 20190523203816702427
2019-05-23 20:38:17,687 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1127.54373884s left)
2019-05-23 20:38:46,866 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203846853000
2019-05-23 20:38:46,892 [salt.minion      :1432][INFO    ][11755] Starting a new job with PID 11755
2019-05-23 20:38:46,923 [salt.minion      :1711][INFO    ][11755] Returning information for job: 20190523203846853000
2019-05-23 20:38:50,491 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1094.73973703s left)
2019-05-23 20:39:17,052 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203917039819
2019-05-23 20:39:17,073 [salt.minion      :1432][INFO    ][11803] Starting a new job with PID 11803
2019-05-23 20:39:17,102 [salt.minion      :1711][INFO    ][11803] Returning information for job: 20190523203917039819
2019-05-23 20:39:23,158 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1062.07210183s left)
2019-05-23 20:39:47,249 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523203947232622
2019-05-23 20:39:47,275 [salt.minion      :1432][INFO    ][11832] Starting a new job with PID 11832
2019-05-23 20:39:47,311 [salt.minion      :1711][INFO    ][11832] Returning information for job: 20190523203947232622
2019-05-23 20:39:56,230 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1029.00053096s left)
2019-05-23 20:40:17,277 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204017261430
2019-05-23 20:40:17,303 [salt.minion      :1432][INFO    ][11881] Starting a new job with PID 11881
2019-05-23 20:40:17,335 [salt.minion      :1711][INFO    ][11881] Returning information for job: 20190523204017261430
2019-05-23 20:40:29,110 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (996.120237827s left)
2019-05-23 20:40:47,502 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204047487681
2019-05-23 20:40:47,525 [salt.minion      :1432][INFO    ][11915] Starting a new job with PID 11915
2019-05-23 20:40:47,556 [salt.minion      :1711][INFO    ][11915] Returning information for job: 20190523204047487681
2019-05-23 20:41:02,175 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (963.055145025s left)
2019-05-23 20:41:17,539 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204117524980
2019-05-23 20:41:17,565 [salt.minion      :1432][INFO    ][11984] Starting a new job with PID 11984
2019-05-23 20:41:17,593 [salt.minion      :1711][INFO    ][11984] Returning information for job: 20190523204117524980
2019-05-23 20:41:34,933 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (930.297473907s left)
2019-05-23 20:41:47,562 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204147551678
2019-05-23 20:41:47,579 [salt.minion      :1432][INFO    ][12021] Starting a new job with PID 12021
2019-05-23 20:41:47,614 [salt.minion      :1711][INFO    ][12021] Returning information for job: 20190523204147551678
2019-05-23 20:42:07,696 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (897.534970045s left)
2019-05-23 20:42:17,621 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204217607816
2019-05-23 20:42:17,650 [salt.minion      :1432][INFO    ][12068] Starting a new job with PID 12068
2019-05-23 20:42:17,683 [salt.minion      :1711][INFO    ][12068] Returning information for job: 20190523204217607816
2019-05-23 20:42:40,488 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (864.742786884s left)
2019-05-23 20:42:47,694 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204247676251
2019-05-23 20:42:47,722 [salt.minion      :1432][INFO    ][12099] Starting a new job with PID 12099
2019-05-23 20:42:47,753 [salt.minion      :1711][INFO    ][12099] Returning information for job: 20190523204247676251
2019-05-23 20:43:13,366 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (831.864245892s left)
2019-05-23 20:43:17,802 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204317785430
2019-05-23 20:43:17,831 [salt.minion      :1432][INFO    ][12146] Starting a new job with PID 12146
2019-05-23 20:43:17,860 [salt.minion      :1711][INFO    ][12146] Returning information for job: 20190523204317785430
2019-05-23 20:43:46,296 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (798.934643984s left)
2019-05-23 20:43:47,929 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204347916112
2019-05-23 20:43:47,952 [salt.minion      :1432][INFO    ][12177] Starting a new job with PID 12177
2019-05-23 20:43:47,987 [salt.minion      :1711][INFO    ][12177] Returning information for job: 20190523204347916112
2019-05-23 20:44:18,068 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204418058083
2019-05-23 20:44:18,094 [salt.minion      :1432][INFO    ][12225] Starting a new job with PID 12225
2019-05-23 20:44:18,131 [salt.minion      :1711][INFO    ][12225] Returning information for job: 20190523204418058083
2019-05-23 20:44:19,084 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (766.146949053s left)
2019-05-23 20:44:48,244 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204448230926
2019-05-23 20:44:48,272 [salt.minion      :1432][INFO    ][12254] Starting a new job with PID 12254
2019-05-23 20:44:48,301 [salt.minion      :1711][INFO    ][12254] Returning information for job: 20190523204448230926
2019-05-23 20:44:51,911 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (733.319412947s left)
2019-05-23 20:45:18,400 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204518386336
2019-05-23 20:45:18,431 [salt.minion      :1432][INFO    ][12300] Starting a new job with PID 12300
2019-05-23 20:45:18,460 [salt.minion      :1711][INFO    ][12300] Returning information for job: 20190523204518386336
2019-05-23 20:45:24,688 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (700.542955875s left)
2019-05-23 20:45:48,420 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204548406195
2019-05-23 20:45:48,444 [salt.minion      :1432][INFO    ][12330] Starting a new job with PID 12330
2019-05-23 20:45:48,481 [salt.minion      :1711][INFO    ][12330] Returning information for job: 20190523204548406195
2019-05-23 20:45:57,379 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (667.85184598s left)
2019-05-23 20:46:18,633 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204618623595
2019-05-23 20:46:18,656 [salt.minion      :1432][INFO    ][12377] Starting a new job with PID 12377
2019-05-23 20:46:18,684 [salt.minion      :1711][INFO    ][12377] Returning information for job: 20190523204618623595
2019-05-23 20:46:30,443 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (634.787858009s left)
2019-05-23 20:46:48,680 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204648668098
2019-05-23 20:46:48,707 [salt.minion      :1432][INFO    ][12409] Starting a new job with PID 12409
2019-05-23 20:46:48,740 [salt.minion      :1711][INFO    ][12409] Returning information for job: 20190523204648668098
2019-05-23 20:47:03,315 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (601.915348053s left)
2019-05-23 20:47:18,907 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204718891674
2019-05-23 20:47:18,934 [salt.minion      :1432][INFO    ][12455] Starting a new job with PID 12455
2019-05-23 20:47:18,965 [salt.minion      :1711][INFO    ][12455] Returning information for job: 20190523204718891674
2019-05-23 20:47:36,092 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (569.138968945s left)
2019-05-23 20:47:48,983 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204748966910
2019-05-23 20:47:49,013 [salt.minion      :1432][INFO    ][12484] Starting a new job with PID 12484
2019-05-23 20:47:49,045 [salt.minion      :1711][INFO    ][12484] Returning information for job: 20190523204748966910
2019-05-23 20:48:08,820 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (536.410538912s left)
2019-05-23 20:48:19,038 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204819023334
2019-05-23 20:48:19,067 [salt.minion      :1432][INFO    ][12531] Starting a new job with PID 12531
2019-05-23 20:48:19,095 [salt.minion      :1711][INFO    ][12531] Returning information for job: 20190523204819023334
2019-05-23 20:48:41,587 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (503.643205881s left)
2019-05-23 20:48:49,164 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204849150369
2019-05-23 20:48:49,194 [salt.minion      :1432][INFO    ][12562] Starting a new job with PID 12562
2019-05-23 20:48:49,226 [salt.minion      :1711][INFO    ][12562] Returning information for job: 20190523204849150369
2019-05-23 20:49:14,506 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (470.724266052s left)
2019-05-23 20:49:19,258 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204919244355
2019-05-23 20:49:19,283 [salt.minion      :1432][INFO    ][12609] Starting a new job with PID 12609
2019-05-23 20:49:19,314 [salt.minion      :1711][INFO    ][12609] Returning information for job: 20190523204919244355
2019-05-23 20:49:47,301 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (437.92955184s left)
2019-05-23 20:49:49,364 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523204949350295
2019-05-23 20:49:49,387 [salt.minion      :1432][INFO    ][12641] Starting a new job with PID 12641
2019-05-23 20:49:49,416 [salt.minion      :1711][INFO    ][12641] Returning information for job: 20190523204949350295
2019-05-23 20:50:19,494 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205019484955
2019-05-23 20:50:19,509 [salt.minion      :1432][INFO    ][12690] Starting a new job with PID 12690
2019-05-23 20:50:19,535 [salt.minion      :1711][INFO    ][12690] Returning information for job: 20190523205019484955
2019-05-23 20:50:19,878 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (405.352275848s left)
2019-05-23 20:50:49,666 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205049652747
2019-05-23 20:50:49,690 [salt.minion      :1432][INFO    ][12849] Starting a new job with PID 12849
2019-05-23 20:50:49,723 [salt.minion      :1711][INFO    ][12849] Returning information for job: 20190523205049652747
2019-05-23 20:50:52,674 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (372.556221008s left)
2019-05-23 20:51:19,687 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205119675477
2019-05-23 20:51:19,715 [salt.minion      :1432][INFO    ][12913] Starting a new job with PID 12913
2019-05-23 20:51:19,752 [salt.minion      :1711][INFO    ][12913] Returning information for job: 20190523205119675477
2019-05-23 20:51:25,572 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (339.658314943s left)
2019-05-23 20:51:49,911 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205149896262
2019-05-23 20:51:49,935 [salt.minion      :1432][INFO    ][12949] Starting a new job with PID 12949
2019-05-23 20:51:49,967 [salt.minion      :1711][INFO    ][12949] Returning information for job: 20190523205149896262
2019-05-23 20:51:58,427 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (306.803575993s left)
2019-05-23 20:52:19,974 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205219960258
2019-05-23 20:52:20,002 [salt.minion      :1432][INFO    ][12996] Starting a new job with PID 12996
2019-05-23 20:52:20,032 [salt.minion      :1711][INFO    ][12996] Returning information for job: 20190523205219960258
2019-05-23 20:52:31,486 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (273.744127989s left)
2019-05-23 20:52:50,016 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205250002181
2019-05-23 20:52:50,040 [salt.minion      :1432][INFO    ][13025] Starting a new job with PID 13025
2019-05-23 20:52:50,071 [salt.minion      :1711][INFO    ][13025] Returning information for job: 20190523205250002181
2019-05-23 20:53:04,450 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (240.780555964s left)
2019-05-23 20:53:20,122 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205320107239
2019-05-23 20:53:20,149 [salt.minion      :1432][INFO    ][13070] Starting a new job with PID 13070
2019-05-23 20:53:20,179 [salt.minion      :1711][INFO    ][13070] Returning information for job: 20190523205320107239
2019-05-23 20:53:37,144 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (208.086331844s left)
2019-05-23 20:53:50,238 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205350222336
2019-05-23 20:53:50,267 [salt.minion      :1432][INFO    ][13104] Starting a new job with PID 13104
2019-05-23 20:53:50,295 [salt.minion      :1711][INFO    ][13104] Returning information for job: 20190523205350222336
2019-05-23 20:54:09,957 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (175.273686886s left)
2019-05-23 20:54:20,365 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205420349058
2019-05-23 20:54:20,394 [salt.minion      :1432][INFO    ][13146] Starting a new job with PID 13146
2019-05-23 20:54:20,425 [salt.minion      :1711][INFO    ][13146] Returning information for job: 20190523205420349058
2019-05-23 20:54:42,722 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (142.509299994s left)
2019-05-23 20:54:50,498 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205450485527
2019-05-23 20:54:50,521 [salt.minion      :1432][INFO    ][13180] Starting a new job with PID 13180
2019-05-23 20:54:50,554 [salt.minion      :1711][INFO    ][13180] Returning information for job: 20190523205450485527
2019-05-23 20:55:15,581 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (109.649921894s left)
2019-05-23 20:55:20,676 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205520659366
2019-05-23 20:55:20,707 [salt.minion      :1432][INFO    ][13225] Starting a new job with PID 13225
2019-05-23 20:55:20,736 [salt.minion      :1711][INFO    ][13225] Returning information for job: 20190523205520659366
2019-05-23 20:55:48,338 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (76.8923139572s left)
2019-05-23 20:55:50,836 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205550822803
2019-05-23 20:55:50,863 [salt.minion      :1432][INFO    ][13261] Starting a new job with PID 13261
2019-05-23 20:55:50,891 [salt.minion      :1711][INFO    ][13261] Returning information for job: 20190523205550822803
2019-05-23 20:56:21,021 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205621012484
2019-05-23 20:56:21,044 [salt.minion      :1432][INFO    ][13306] Starting a new job with PID 13306
2019-05-23 20:56:21,072 [salt.minion      :1711][INFO    ][13306] Returning information for job: 20190523205621012484
2019-05-23 20:56:21,272 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (43.9587519169s left)
2019-05-23 20:56:51,043 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205651028611
2019-05-23 20:56:51,070 [salt.minion      :1432][INFO    ][13330] Starting a new job with PID 13330
2019-05-23 20:56:51,099 [salt.minion      :1711][INFO    ][13330] Returning information for job: 20190523205651028611
2019-05-23 20:56:54,161 [salt.loaded.ext.module.maas:1023][INFO    ][7724] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (11.0699930191s left)
2019-05-23 20:57:21,239 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205721227087
2019-05-23 20:57:21,262 [salt.minion      :1432][INFO    ][13375] Starting a new job with PID 13375
2019-05-23 20:57:21,292 [salt.minion      :1711][INFO    ][13375] Returning information for job: 20190523205721227087
2019-05-23 20:57:27,019 [salt.state       :302 ][ERROR   ][7724] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-23 20:57:27,020 [salt.state       :1951][INFO    ][7724] Completed state [maas.wait_for_machine_status] at time 20:57:27.020082 duration_in_ms=2271802.872
2019-05-23 20:57:27,027 [salt.minion      :1711][INFO    ][7724] Returning information for job: 20190523201927021584
2019-05-23 20:57:37,950 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command pillar.get with jid 20190523205737939411
2019-05-23 20:57:37,973 [salt.minion      :1432][INFO    ][13394] Starting a new job with PID 13394
2019-05-23 20:57:37,983 [salt.minion      :1711][INFO    ][13394] Returning information for job: 20190523205737939411
2019-05-23 20:57:38,668 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command service.status with jid 20190523205738653205
2019-05-23 20:57:38,694 [salt.minion      :1432][INFO    ][13399] Starting a new job with PID 13399
2019-05-23 20:57:39,447 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13399] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-23 20:57:39,491 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13399] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-23 20:57:39,511 [salt.minion      :1711][INFO    ][13399] Returning information for job: 20190523205738653205
2019-05-23 20:57:40,231 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523205740218585
2019-05-23 20:57:40,259 [salt.minion      :1432][INFO    ][13410] Starting a new job with PID 13410
2019-05-23 20:57:46,230 [salt.state       :915 ][INFO    ][13410] Loading fresh modules for state activity
2019-05-23 20:57:46,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command 'salt-minion --version' in directory '/root'
2019-05-23 20:57:47,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command 'salt-minion --version' in directory '/root'
2019-05-23 20:57:48,328 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command 'salt-minion --version' in directory '/root'
2019-05-23 20:57:48,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command 'salt-minion --version' in directory '/root'
2019-05-23 20:57:50,916 [salt.state       :1780][INFO    ][13410] Running state [salt-minion] at time 20:57:50.916183
2019-05-23 20:57:50,916 [salt.state       :1813][INFO    ][13410] Executing state pkg.installed for [salt-minion]
2019-05-23 20:57:50,917 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-23 20:57:51,033 [salt.state       :300 ][INFO    ][13410] All specified packages are already installed
2019-05-23 20:57:51,034 [salt.state       :1951][INFO    ][13410] Completed state [salt-minion] at time 20:57:51.034061 duration_in_ms=117.877
2019-05-23 20:57:51,034 [salt.state       :1780][INFO    ][13410] Running state [salt_minion_dependency_packages] at time 20:57:51.034446
2019-05-23 20:57:51,034 [salt.state       :1813][INFO    ][13410] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-23 20:57:51,044 [salt.state       :300 ][INFO    ][13410] All specified packages are already installed
2019-05-23 20:57:51,044 [salt.state       :1951][INFO    ][13410] Completed state [salt_minion_dependency_packages] at time 20:57:51.044916 duration_in_ms=10.47
2019-05-23 20:57:51,048 [salt.state       :1780][INFO    ][13410] Running state [/etc/salt/minion.d/minion.conf] at time 20:57:51.048047
2019-05-23 20:57:51,048 [salt.state       :1813][INFO    ][13410] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-23 20:57:51,323 [salt.state       :300 ][INFO    ][13410] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-23 20:57:51,324 [salt.state       :1951][INFO    ][13410] Completed state [/etc/salt/minion.d/minion.conf] at time 20:57:51.324215 duration_in_ms=276.168
2019-05-23 20:57:51,324 [salt.state       :1780][INFO    ][13410] Running state [python-netaddr] at time 20:57:51.324523
2019-05-23 20:57:51,324 [salt.state       :1813][INFO    ][13410] Executing state pkg.installed for [python-netaddr]
2019-05-23 20:57:51,333 [salt.state       :300 ][INFO    ][13410] All specified packages are already installed
2019-05-23 20:57:51,334 [salt.state       :1951][INFO    ][13410] Completed state [python-netaddr] at time 20:57:51.334087 duration_in_ms=9.563
2019-05-23 20:57:51,337 [salt.state       :1780][INFO    ][13410] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:57:51.337252
2019-05-23 20:57:51,337 [salt.state       :1813][INFO    ][13410] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-23 20:57:51,351 [salt.state       :300 ][INFO    ][13410] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-23 20:57:51,351 [salt.state       :1951][INFO    ][13410] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:57:51.351363 duration_in_ms=14.111
2019-05-23 20:57:51,353 [salt.state       :1780][INFO    ][13410] Running state [salt-minion] at time 20:57:51.352974
2019-05-23 20:57:51,353 [salt.state       :1813][INFO    ][13410] Executing state service.running for [salt-minion]
2019-05-23 20:57:51,354 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-23 20:57:51,399 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-23 20:57:51,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-23 20:57:51,441 [salt.state       :300 ][INFO    ][13410] The service salt-minion is already running
2019-05-23 20:57:51,442 [salt.state       :1951][INFO    ][13410] Completed state [salt-minion] at time 20:57:51.442446 duration_in_ms=89.471
2019-05-23 20:57:51,447 [salt.state       :1780][INFO    ][13410] Running state [/etc/salt/grains.d] at time 20:57:51.445657
2019-05-23 20:57:51,448 [salt.state       :1813][INFO    ][13410] Executing state file.directory for [/etc/salt/grains.d]
2019-05-23 20:57:51,450 [salt.state       :300 ][INFO    ][13410] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-23 20:57:51,450 [salt.state       :1951][INFO    ][13410] Completed state [/etc/salt/grains.d] at time 20:57:51.450551 duration_in_ms=4.894
2019-05-23 20:57:51,451 [salt.state       :1780][INFO    ][13410] Running state [/etc/salt/grains] at time 20:57:51.451868
2019-05-23 20:57:51,452 [salt.state       :1813][INFO    ][13410] Executing state file.managed for [/etc/salt/grains]
2019-05-23 20:57:51,453 [salt.state       :300 ][INFO    ][13410] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-23 20:57:51,453 [salt.state       :1951][INFO    ][13410] Completed state [/etc/salt/grains] at time 20:57:51.453876 duration_in_ms=2.009
2019-05-23 20:57:51,454 [salt.state       :1780][INFO    ][13410] Running state [/etc/salt/grains.d/placeholder] at time 20:57:51.454825
2019-05-23 20:57:51,455 [salt.state       :1813][INFO    ][13410] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-23 20:57:51,456 [salt.state       :300 ][INFO    ][13410] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-23 20:57:51,456 [salt.state       :1951][INFO    ][13410] Completed state [/etc/salt/grains.d/placeholder] at time 20:57:51.456826 duration_in_ms=2.001
2019-05-23 20:57:51,459 [salt.state       :1780][INFO    ][13410] Running state [/etc/salt/grains.d/sphinx] at time 20:57:51.459543
2019-05-23 20:57:51,459 [salt.state       :1813][INFO    ][13410] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-23 20:57:51,461 [salt.state       :300 ][INFO    ][13410] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-23 20:57:51,461 [salt.state       :1951][INFO    ][13410] Completed state [/etc/salt/grains.d/sphinx] at time 20:57:51.461425 duration_in_ms=1.882
2019-05-23 20:57:51,463 [salt.state       :1780][INFO    ][13410] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:57:51.463520
2019-05-23 20:57:51,463 [salt.state       :1813][INFO    ][13410] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-23 20:57:51,464 [salt.state       :300 ][INFO    ][13410] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-23 20:57:51,464 [salt.state       :1951][INFO    ][13410] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:57:51.464389 duration_in_ms=0.869
2019-05-23 20:57:51,464 [salt.state       :1780][INFO    ][13410] Running state [/etc/salt/grains.d/dns_records] at time 20:57:51.464913
2019-05-23 20:57:51,465 [salt.state       :1813][INFO    ][13410] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-23 20:57:51,466 [salt.state       :300 ][INFO    ][13410] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-23 20:57:51,466 [salt.state       :1951][INFO    ][13410] Completed state [/etc/salt/grains.d/dns_records] at time 20:57:51.466548 duration_in_ms=1.635
2019-05-23 20:57:51,467 [salt.state       :1780][INFO    ][13410] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:57:51.467522
2019-05-23 20:57:51,467 [salt.state       :1813][INFO    ][13410] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-23 20:57:51,468 [salt.state       :300 ][INFO    ][13410] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-23 20:57:51,468 [salt.state       :1951][INFO    ][13410] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:57:51.468373 duration_in_ms=0.851
2019-05-23 20:57:51,468 [salt.state       :1780][INFO    ][13410] Running state [/etc/salt/grains.d/salt] at time 20:57:51.468901
2019-05-23 20:57:51,469 [salt.state       :1813][INFO    ][13410] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-23 20:57:51,471 [salt.state       :300 ][INFO    ][13410] File /etc/salt/grains.d/salt is in the correct state
2019-05-23 20:57:51,471 [salt.state       :1951][INFO    ][13410] Completed state [/etc/salt/grains.d/salt] at time 20:57:51.471702 duration_in_ms=2.801
2019-05-23 20:57:51,472 [salt.state       :1780][INFO    ][13410] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:57:51.472632
2019-05-23 20:57:51,472 [salt.state       :1813][INFO    ][13410] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-23 20:57:51,473 [salt.state       :300 ][INFO    ][13410] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-23 20:57:51,473 [salt.state       :1951][INFO    ][13410] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:57:51.473482 duration_in_ms=0.85
2019-05-23 20:57:51,475 [salt.state       :1780][INFO    ][13410] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:57:51.475762
2019-05-23 20:57:51,476 [salt.state       :1813][INFO    ][13410] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-23 20:57:51,476 [salt.state       :300 ][INFO    ][13410] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-23 20:57:51,476 [salt.state       :1951][INFO    ][13410] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:57:51.476635 duration_in_ms=0.874
2019-05-23 20:57:51,477 [salt.state       :1780][INFO    ][13410] Running state [mine.update] at time 20:57:51.477347
2019-05-23 20:57:51,477 [salt.state       :1813][INFO    ][13410] Executing state module.wait for [mine.update]
2019-05-23 20:57:51,478 [salt.state       :300 ][INFO    ][13410] No changes made for mine.update
2019-05-23 20:57:51,478 [salt.state       :1951][INFO    ][13410] Completed state [mine.update] at time 20:57:51.478420 duration_in_ms=1.072
2019-05-23 20:57:51,478 [salt.state       :1780][INFO    ][13410] Running state [ca-certificates] at time 20:57:51.478705
2019-05-23 20:57:51,479 [salt.state       :1813][INFO    ][13410] Executing state pkg.installed for [ca-certificates]
2019-05-23 20:57:51,488 [salt.state       :300 ][INFO    ][13410] All specified packages are already installed
2019-05-23 20:57:51,489 [salt.state       :1951][INFO    ][13410] Completed state [ca-certificates] at time 20:57:51.489162 duration_in_ms=10.457
2019-05-23 20:57:51,490 [salt.state       :1780][INFO    ][13410] Running state [update-ca-certificates] at time 20:57:51.490228
2019-05-23 20:57:51,490 [salt.state       :1813][INFO    ][13410] Executing state cmd.wait for [update-ca-certificates]
2019-05-23 20:57:51,490 [salt.state       :300 ][INFO    ][13410] No changes made for update-ca-certificates
2019-05-23 20:57:51,491 [salt.state       :1951][INFO    ][13410] Completed state [update-ca-certificates] at time 20:57:51.491037 duration_in_ms=0.808
2019-05-23 20:57:51,491 [salt.state       :1780][INFO    ][13410] Running state [iptables] at time 20:57:51.491325
2019-05-23 20:57:51,491 [salt.state       :1813][INFO    ][13410] Executing state pkg.installed for [iptables]
2019-05-23 20:57:51,507 [salt.state       :300 ][INFO    ][13410] All specified packages are already installed
2019-05-23 20:57:51,508 [salt.state       :1951][INFO    ][13410] Completed state [iptables] at time 20:57:51.507942 duration_in_ms=16.616
2019-05-23 20:57:51,508 [salt.state       :1780][INFO    ][13410] Running state [iptables-persistent] at time 20:57:51.508232
2019-05-23 20:57:51,508 [salt.state       :1813][INFO    ][13410] Executing state pkg.installed for [iptables-persistent]
2019-05-23 20:57:51,517 [salt.state       :300 ][INFO    ][13410] All specified packages are already installed
2019-05-23 20:57:51,518 [salt.state       :1951][INFO    ][13410] Completed state [iptables-persistent] at time 20:57:51.517687 duration_in_ms=9.455
2019-05-23 20:57:51,519 [salt.state       :1780][INFO    ][13410] Running state [iptables_modules_v4_load] at time 20:57:51.519381
2019-05-23 20:57:51,519 [salt.state       :1813][INFO    ][13410] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-23 20:57:51,520 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command 'lsmod' in directory '/root'
2019-05-23 20:57:51,545 [salt.state       :300 ][INFO    ][13410] Kernel modules iptable_filter, ip_tables are already present
2019-05-23 20:57:51,546 [salt.state       :1951][INFO    ][13410] Completed state [iptables_modules_v4_load] at time 20:57:51.545971 duration_in_ms=26.588
2019-05-23 20:57:51,547 [salt.state       :1780][INFO    ][13410] Running state [/etc/iptables/rules.v4] at time 20:57:51.547242
2019-05-23 20:57:51,547 [salt.state       :1813][INFO    ][13410] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-23 20:57:51,650 [salt.state       :300 ][INFO    ][13410] File /etc/iptables/rules.v4 is in the correct state
2019-05-23 20:57:51,650 [salt.state       :1951][INFO    ][13410] Completed state [/etc/iptables/rules.v4] at time 20:57:51.650623 duration_in_ms=103.381
2019-05-23 20:57:51,651 [salt.state       :1780][INFO    ][13410] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:57:51.651768
2019-05-23 20:57:51,652 [salt.state       :1813][INFO    ][13410] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-23 20:57:51,652 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-23 20:57:51,673 [salt.state       :300 ][INFO    ][13410] onlyif execution failed
2019-05-23 20:57:51,675 [salt.state       :1951][INFO    ][13410] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:57:51.675296 duration_in_ms=23.528
2019-05-23 20:57:51,676 [salt.state       :1780][INFO    ][13410] Running state [netfilter-persistent] at time 20:57:51.676830
2019-05-23 20:57:51,677 [salt.state       :1813][INFO    ][13410] Executing state service.running for [netfilter-persistent]
2019-05-23 20:57:51,680 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-23 20:57:51,700 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-23 20:57:51,723 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-23 20:57:51,745 [salt.state       :300 ][INFO    ][13410] The service netfilter-persistent is already running
2019-05-23 20:57:51,747 [salt.state       :1951][INFO    ][13410] Completed state [netfilter-persistent] at time 20:57:51.747668 duration_in_ms=70.838
2019-05-23 20:57:51,748 [salt.state       :1780][INFO    ][13410] Running state [iptables_extra.remove_stale_tables] at time 20:57:51.748738
2019-05-23 20:57:51,749 [salt.state       :1813][INFO    ][13410] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-23 20:57:51,749 [salt.state       :300 ][INFO    ][13410] No changes made for iptables_extra.remove_stale_tables
2019-05-23 20:57:51,749 [salt.state       :1951][INFO    ][13410] Completed state [iptables_extra.remove_stale_tables] at time 20:57:51.749824 duration_in_ms=1.086
2019-05-23 20:57:51,750 [salt.state       :1780][INFO    ][13410] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:57:51.750166
2019-05-23 20:57:51,750 [salt.state       :1813][INFO    ][13410] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-23 20:57:51,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13410] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-23 20:57:51,768 [salt.state       :300 ][INFO    ][13410] onlyif execution failed
2019-05-23 20:57:51,768 [salt.state       :1951][INFO    ][13410] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:57:51.768605 duration_in_ms=18.439
2019-05-23 20:57:51,772 [salt.state       :1780][INFO    ][13410] Running state [/etc/iptables/rules.v6] at time 20:57:51.772387
2019-05-23 20:57:51,772 [salt.state       :1813][INFO    ][13410] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-23 20:57:51,773 [salt.state       :300 ][INFO    ][13410] File /etc/iptables/rules.v6 is not present
2019-05-23 20:57:51,774 [salt.state       :1951][INFO    ][13410] Completed state [/etc/iptables/rules.v6] at time 20:57:51.774008 duration_in_ms=1.621
2019-05-23 20:57:51,775 [salt.state       :1780][INFO    ][13410] Running state [iptables_extra.flush_all] at time 20:57:51.775132
2019-05-23 20:57:51,775 [salt.state       :1813][INFO    ][13410] Executing state module.wait for [iptables_extra.flush_all]
2019-05-23 20:57:51,776 [salt.state       :300 ][INFO    ][13410] No changes made for iptables_extra.flush_all
2019-05-23 20:57:51,776 [salt.state       :1951][INFO    ][13410] Completed state [iptables_extra.flush_all] at time 20:57:51.776396 duration_in_ms=1.264
2019-05-23 20:57:51,783 [salt.minion      :1711][INFO    ][13410] Returning information for job: 20190523205740218585
2019-05-23 20:57:52,487 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523205752476137
2019-05-23 20:57:52,517 [salt.minion      :1432][INFO    ][13499] Starting a new job with PID 13499
2019-05-23 20:57:53,513 [salt.state       :915 ][INFO    ][13499] Loading fresh modules for state activity
2019-05-23 20:57:54,811 [salt.state       :1780][INFO    ][13499] Running state [maas-rack-controller] at time 20:57:54.811866
2019-05-23 20:57:54,812 [salt.state       :1813][INFO    ][13499] Executing state pkg.installed for [maas-rack-controller]
2019-05-23 20:57:54,813 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13499] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-23 20:57:54,942 [salt.state       :300 ][INFO    ][13499] All specified packages are already installed
2019-05-23 20:57:54,942 [salt.state       :1951][INFO    ][13499] Completed state [maas-rack-controller] at time 20:57:54.942341 duration_in_ms=130.474
2019-05-23 20:57:54,942 [salt.state       :1780][INFO    ][13499] Running state [ipmitool] at time 20:57:54.942709
2019-05-23 20:57:54,943 [salt.state       :1813][INFO    ][13499] Executing state pkg.installed for [ipmitool]
2019-05-23 20:57:54,952 [salt.state       :300 ][INFO    ][13499] All specified packages are already installed
2019-05-23 20:57:54,953 [salt.state       :1951][INFO    ][13499] Completed state [ipmitool] at time 20:57:54.953009 duration_in_ms=10.301
2019-05-23 20:57:54,956 [salt.state       :1780][INFO    ][13499] Running state [/etc/maas/rackd.conf] at time 20:57:54.956471
2019-05-23 20:57:54,956 [salt.state       :1813][INFO    ][13499] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-23 20:57:54,958 [salt.state       :300 ][INFO    ][13499] No changes needed to be made
2019-05-23 20:57:54,958 [salt.state       :1951][INFO    ][13499] Completed state [/etc/maas/rackd.conf] at time 20:57:54.958310 duration_in_ms=1.839
2019-05-23 20:57:54,958 [salt.state       :1780][INFO    ][13499] Running state [/etc/maas/rackd.conf] at time 20:57:54.958592
2019-05-23 20:57:54,958 [salt.state       :1813][INFO    ][13499] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-23 20:57:54,959 [salt.loaded.int.states.file:2298][WARNING ][13499] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-23 20:57:54,960 [salt.state       :300 ][INFO    ][13499] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-23 20:57:54,960 [salt.state       :1951][INFO    ][13499] Completed state [/etc/maas/rackd.conf] at time 20:57:54.960231 duration_in_ms=1.638
2019-05-23 20:57:54,961 [salt.state       :1780][INFO    ][13499] Running state [maas-rackd] at time 20:57:54.961286
2019-05-23 20:57:54,961 [salt.state       :1813][INFO    ][13499] Executing state service.running for [maas-rackd]
2019-05-23 20:57:54,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13499] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-23 20:57:55,007 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13499] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-23 20:57:55,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13499] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-23 20:57:55,054 [salt.state       :300 ][INFO    ][13499] The service maas-rackd is already running
2019-05-23 20:57:55,055 [salt.state       :1951][INFO    ][13499] Completed state [maas-rackd] at time 20:57:55.054889 duration_in_ms=93.602
2019-05-23 20:57:55,057 [salt.minion      :1711][INFO    ][13499] Returning information for job: 20190523205752476137
2019-05-23 20:57:55,737 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523205755727152
2019-05-23 20:57:55,768 [salt.minion      :1432][INFO    ][13522] Starting a new job with PID 13522
2019-05-23 20:57:56,718 [salt.state       :915 ][INFO    ][13522] Loading fresh modules for state activity
2019-05-23 20:57:58,125 [salt.state       :1780][INFO    ][13522] Running state [maas-region-controller] at time 20:57:58.125474
2019-05-23 20:57:58,126 [salt.state       :1813][INFO    ][13522] Executing state pkg.installed for [maas-region-controller]
2019-05-23 20:57:58,127 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-23 20:57:58,247 [salt.state       :300 ][INFO    ][13522] All specified packages are already installed
2019-05-23 20:57:58,248 [salt.state       :1951][INFO    ][13522] Completed state [maas-region-controller] at time 20:57:58.248204 duration_in_ms=122.73
2019-05-23 20:57:58,248 [salt.state       :1780][INFO    ][13522] Running state [python-oauth] at time 20:57:58.248585
2019-05-23 20:57:58,248 [salt.state       :1813][INFO    ][13522] Executing state pkg.installed for [python-oauth]
2019-05-23 20:57:58,258 [salt.state       :300 ][INFO    ][13522] All specified packages are already installed
2019-05-23 20:57:58,259 [salt.state       :1951][INFO    ][13522] Completed state [python-oauth] at time 20:57:58.258935 duration_in_ms=10.35
2019-05-23 20:57:58,263 [salt.state       :1780][INFO    ][13522] Running state [/etc/maas/regiond.conf] at time 20:57:58.263584
2019-05-23 20:57:58,263 [salt.state       :1813][INFO    ][13522] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-23 20:57:58,268 [salt.state       :300 ][INFO    ][13522] No changes needed to be made
2019-05-23 20:57:58,268 [salt.state       :1951][INFO    ][13522] Completed state [/etc/maas/regiond.conf] at time 20:57:58.268904 duration_in_ms=5.321
2019-05-23 20:57:58,269 [salt.state       :1780][INFO    ][13522] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:57:58.269449
2019-05-23 20:57:58,270 [salt.state       :1813][INFO    ][13522] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-23 20:57:58,335 [salt.state       :300 ][INFO    ][13522] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-23 20:57:58,335 [salt.state       :1951][INFO    ][13522] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:57:58.335680 duration_in_ms=66.231
2019-05-23 20:57:58,336 [salt.state       :1780][INFO    ][13522] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:57:58.336216
2019-05-23 20:57:58,336 [salt.state       :1813][INFO    ][13522] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-23 20:57:58,340 [salt.state       :300 ][INFO    ][13522] No changes needed to be made
2019-05-23 20:57:58,341 [salt.state       :1951][INFO    ][13522] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:57:58.341021 duration_in_ms=4.805
2019-05-23 20:57:58,341 [salt.state       :1780][INFO    ][13522] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:57:58.341536
2019-05-23 20:57:58,342 [salt.state       :1813][INFO    ][13522] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-23 20:57:58,347 [salt.state       :300 ][INFO    ][13522] No changes needed to be made
2019-05-23 20:57:58,347 [salt.state       :1951][INFO    ][13522] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:57:58.347569 duration_in_ms=6.033
2019-05-23 20:57:58,348 [salt.state       :1780][INFO    ][13522] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:57:58.348085
2019-05-23 20:57:58,348 [salt.state       :1813][INFO    ][13522] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-23 20:57:58,373 [salt.state       :300 ][INFO    ][13522] No changes needed to be made
2019-05-23 20:57:58,373 [salt.state       :1951][INFO    ][13522] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:57:58.373625 duration_in_ms=25.54
2019-05-23 20:57:58,374 [salt.state       :1780][INFO    ][13522] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:57:58.374654
2019-05-23 20:57:58,374 [salt.state       :1813][INFO    ][13522] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-23 20:57:58,392 [salt.state       :300 ][INFO    ][13522] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-23 20:57:58,393 [salt.state       :1951][INFO    ][13522] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:57:58.393149 duration_in_ms=18.494
2019-05-23 20:57:58,396 [salt.state       :1780][INFO    ][13522] Running state [a2enmod headers] at time 20:57:58.396456
2019-05-23 20:57:58,397 [salt.state       :1813][INFO    ][13522] Executing state cmd.run for [a2enmod headers]
2019-05-23 20:57:58,399 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command 'a2enmod headers' in directory '/root'
2019-05-23 20:57:58,481 [salt.state       :300 ][INFO    ][13522] {'pid': 13544, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-23 20:57:58,481 [salt.state       :1951][INFO    ][13522] Completed state [a2enmod headers] at time 20:57:58.481637 duration_in_ms=85.182
2019-05-23 20:57:58,482 [salt.state       :1780][INFO    ][13522] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:57:58.482190
2019-05-23 20:57:58,482 [salt.state       :1813][INFO    ][13522] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-23 20:57:58,501 [salt.state       :300 ][INFO    ][13522] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-23 20:57:58,501 [salt.state       :1951][INFO    ][13522] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:57:58.501368 duration_in_ms=19.178
2019-05-23 20:57:58,505 [salt.state       :1780][INFO    ][13522] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:57:58.505208
2019-05-23 20:57:58,506 [salt.state       :1813][INFO    ][13522] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-23 20:57:58,584 [salt.state       :300 ][INFO    ][13522] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-23 20:57:58,584 [salt.state       :1951][INFO    ][13522] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:57:58.584752 duration_in_ms=79.543
2019-05-23 20:57:58,585 [salt.state       :1780][INFO    ][13522] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:57:58.585340
2019-05-23 20:57:58,585 [salt.state       :1813][INFO    ][13522] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-23 20:57:58,678 [salt.state       :300 ][INFO    ][13522] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-23 20:57:58,678 [salt.state       :1951][INFO    ][13522] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:57:58.678761 duration_in_ms=93.421
2019-05-23 20:57:58,679 [salt.state       :1780][INFO    ][13522] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:57:58.679350
2019-05-23 20:57:58,679 [salt.state       :1813][INFO    ][13522] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-23 20:57:58,761 [salt.state       :300 ][INFO    ][13522] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-23 20:57:58,761 [salt.state       :1951][INFO    ][13522] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:57:58.761865 duration_in_ms=82.515
2019-05-23 20:57:58,762 [salt.state       :1780][INFO    ][13522] Running state [/root/.pgpass] at time 20:57:58.762168
2019-05-23 20:57:58,762 [salt.state       :1813][INFO    ][13522] Executing state file.managed for [/root/.pgpass]
2019-05-23 20:57:58,809 [salt.state       :300 ][INFO    ][13522] File /root/.pgpass is in the correct state
2019-05-23 20:57:58,810 [salt.state       :1951][INFO    ][13522] Completed state [/root/.pgpass] at time 20:57:58.809956 duration_in_ms=47.788
2019-05-23 20:57:58,815 [salt.state       :1780][INFO    ][13522] Running state [maas-region syncdb --noinput] at time 20:57:58.815777
2019-05-23 20:57:58,816 [salt.state       :1813][INFO    ][13522] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-23 20:57:58,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-23 20:58:01,546 [salt.state       :300 ][INFO    ][13522] {'pid': 13576, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, auth, piston3, maasserver, contenttypes, sessions, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-23 20:58:01,547 [salt.state       :1951][INFO    ][13522] Completed state [maas-region syncdb --noinput] at time 20:58:01.547243 duration_in_ms=2731.464
2019-05-23 20:58:01,547 [salt.state       :2022][WARNING ][13522] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-23 20:58:01,549 [salt.state       :1780][INFO    ][13522] Running state [maas-regiond] at time 20:58:01.549347
2019-05-23 20:58:01,550 [salt.state       :1813][INFO    ][13522] Executing state service.running for [maas-regiond]
2019-05-23 20:58:01,551 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-23 20:58:01,588 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-23 20:58:01,604 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-23 20:58:01,625 [salt.state       :300 ][INFO    ][13522] The service maas-regiond is already running
2019-05-23 20:58:01,626 [salt.state       :1951][INFO    ][13522] Completed state [maas-regiond] at time 20:58:01.625885 duration_in_ms=76.538
2019-05-23 20:58:01,629 [salt.state       :1780][INFO    ][13522] Running state [bind9] at time 20:58:01.629137
2019-05-23 20:58:01,629 [salt.state       :1813][INFO    ][13522] Executing state service.running for [bind9]
2019-05-23 20:58:01,631 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-23 20:58:01,650 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-23 20:58:01,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-23 20:58:01,689 [salt.state       :300 ][INFO    ][13522] The service bind9 is already running
2019-05-23 20:58:01,689 [salt.state       :1951][INFO    ][13522] Completed state [bind9] at time 20:58:01.689538 duration_in_ms=60.402
2019-05-23 20:58:01,692 [salt.state       :1780][INFO    ][13522] Running state [apache2] at time 20:58:01.692364
2019-05-23 20:58:01,692 [salt.state       :1813][INFO    ][13522] Executing state service.running for [apache2]
2019-05-23 20:58:01,696 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-23 20:58:01,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-23 20:58:01,733 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-23 20:58:01,759 [salt.state       :300 ][INFO    ][13522] The service apache2 is already running
2019-05-23 20:58:01,760 [salt.state       :1951][INFO    ][13522] Completed state [apache2] at time 20:58:01.759992 duration_in_ms=67.628
2019-05-23 20:58:01,761 [salt.state       :1780][INFO    ][13522] Running state [maasng.wait_for_http_code] at time 20:58:01.761764
2019-05-23 20:58:01,762 [salt.state       :1813][INFO    ][13522] Executing state module.run for [maasng.wait_for_http_code]
2019-05-23 20:58:01,762 [salt.utils.decorators:613 ][WARNING ][13522] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:58:01,773 [salt.state       :300 ][INFO    ][13522] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-23 20:58:01,773 [salt.state       :1951][INFO    ][13522] Completed state [maasng.wait_for_http_code] at time 20:58:01.773830 duration_in_ms=12.065
2019-05-23 20:58:01,774 [salt.state       :1780][INFO    ][13522] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:58:01.774736
2019-05-23 20:58:01,775 [salt.state       :1813][INFO    ][13522] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-23 20:58:01,775 [salt.state       :300 ][INFO    ][13522] /var/lib/maas/.setup_admin exists
2019-05-23 20:58:01,775 [salt.state       :1951][INFO    ][13522] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:58:01.775751 duration_in_ms=1.015
2019-05-23 20:58:01,776 [salt.state       :1780][INFO    ][13522] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:58:01.776468
2019-05-23 20:58:01,776 [salt.state       :1813][INFO    ][13522] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 20:58:01,777 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13522] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 20:58:03,678 [salt.state       :300 ][INFO    ][13522] {'pid': 13603, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 20:58:03,679 [salt.state       :1951][INFO    ][13522] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:58:03.678924 duration_in_ms=1902.455
2019-05-23 20:58:03,684 [salt.state       :1780][INFO    ][13522] Running state [maas_region_boot_source_resources_mirror] at time 20:58:03.684133
2019-05-23 20:58:03,684 [salt.state       :1813][INFO    ][13522] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-23 20:58:03,774 [salt.state       :300 ][INFO    ][13522] {'changes': {}}
2019-05-23 20:58:03,774 [salt.state       :1951][INFO    ][13522] Completed state [maas_region_boot_source_resources_mirror] at time 20:58:03.774840 duration_in_ms=90.706
2019-05-23 20:58:03,775 [salt.state       :1780][INFO    ][13522] Running state [maasng.boot_resources_import] at time 20:58:03.775831
2019-05-23 20:58:03,776 [salt.state       :1813][INFO    ][13522] Executing state module.run for [maasng.boot_resources_import]
2019-05-23 20:58:03,776 [salt.utils.decorators:613 ][WARNING ][13522] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:58:03,862 [salt.loaded.ext.module.maasng:1600][INFO    ][13522] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-23 20:58:08,923 [salt.loaded.ext.module.maasng:1600][INFO    ][13522] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-23 20:58:10,779 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205810764325
2019-05-23 20:58:10,806 [salt.minion      :1432][INFO    ][13632] Starting a new job with PID 13632
2019-05-23 20:58:10,831 [salt.minion      :1711][INFO    ][13632] Returning information for job: 20190523205810764325
2019-05-23 20:58:14,028 [salt.state       :300 ][INFO    ][13522] {'ret': True}
2019-05-23 20:58:14,029 [salt.state       :1951][INFO    ][13522] Completed state [maasng.boot_resources_import] at time 20:58:14.029013 duration_in_ms=10253.177
2019-05-23 20:58:14,033 [salt.state       :1780][INFO    ][13522] Running state [maas_region_boot_sources_selection_xenial] at time 20:58:14.032909
2019-05-23 20:58:14,033 [salt.state       :1813][INFO    ][13522] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-23 20:58:14,214 [salt.state       :300 ][INFO    ][13522] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-23 20:58:14,215 [salt.state       :1951][INFO    ][13522] Completed state [maas_region_boot_sources_selection_xenial] at time 20:58:14.215031 duration_in_ms=182.121
2019-05-23 20:58:14,216 [salt.state       :1780][INFO    ][13522] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:58:14.216270
2019-05-23 20:58:14,216 [salt.state       :1813][INFO    ][13522] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-23 20:58:14,217 [salt.utils.decorators:613 ][WARNING ][13522] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:58:14,218 [salt.loaded.ext.module.maasng:1771][INFO    ][13522] boot-sources sync initiated for ALL Rack's
2019-05-23 20:58:15,329 [salt.state       :300 ][INFO    ][13522] {'ret': True}
2019-05-23 20:58:15,331 [salt.state       :1951][INFO    ][13522] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:58:15.331542 duration_in_ms=1115.271
2019-05-23 20:58:15,333 [salt.state       :1780][INFO    ][13522] Running state [maas.process_maas_config] at time 20:58:15.333366
2019-05-23 20:58:15,334 [salt.state       :1813][INFO    ][13522] Executing state module.run for [maas.process_maas_config]
2019-05-23 20:58:15,334 [salt.utils.decorators:613 ][WARNING ][13522] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:58:15,335 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=enable_http_proxy value=True
2019-05-23 20:58:15,392 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=upstream_dns value=8.8.8.8
2019-05-23 20:58:15,443 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=commissioning_distro_series value=xenial
2019-05-23 20:58:16,872 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=default_osystem value=ubuntu
2019-05-23 20:58:16,937 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=active_discovery_interval value=600
2019-05-23 20:58:16,996 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=dnssec_validation value=no
2019-05-23 20:58:17,047 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=maas_name value=mas01
2019-05-23 20:58:17,093 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=network_discovery value=enabled
2019-05-23 20:58:17,191 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=enable_third_party_drivers value=True
2019-05-23 20:58:17,242 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=default_storage_layout value=lvm
2019-05-23 20:58:17,301 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=ntp_external_only value=True
2019-05-23 20:58:17,351 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-23 20:58:17,401 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=default_distro_series value=xenial
2019-05-23 20:58:17,459 [salt.loaded.ext.module.maas:92  ][INFO    ][13522] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-23 20:58:17,563 [salt.state       :300 ][INFO    ][13522] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-23 20:58:17,564 [salt.state       :1951][INFO    ][13522] Completed state [maas.process_maas_config] at time 20:58:17.564201 duration_in_ms=2230.834
2019-05-23 20:58:17,565 [salt.state       :1780][INFO    ][13522] Running state [pxe_admin] at time 20:58:17.565238
2019-05-23 20:58:17,565 [salt.state       :1813][INFO    ][13522] Executing state maasng.fabric_present for [pxe_admin]
2019-05-23 20:58:17,616 [salt.loaded.ext.module.maasng:945 ][INFO    ][13522] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xetsmh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-23 20:58:17,677 [salt.loaded.ext.module.maasng:1008][WARNING ][13522] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-23 20:58:17,679 [salt.loaded.ext.module.maasng:1011][WARNING ][13522] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-23 20:58:17,740 [salt.state       :300 ][INFO    ][13522] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-23 20:58:17,741 [salt.state       :1951][INFO    ][13522] Completed state [pxe_admin] at time 20:58:17.741259 duration_in_ms=176.02
2019-05-23 20:58:17,741 [salt.state       :1780][INFO    ][13522] Running state [vlan 0] at time 20:58:17.741629
2019-05-23 20:58:17,742 [salt.state       :1813][INFO    ][13522] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-23 20:58:17,802 [salt.loaded.ext.module.maasng:945 ][INFO    ][13522] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xetsmh', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-23 20:58:17,901 [salt.loaded.ext.module.maasng:945 ][INFO    ][13522] [{u'id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-23 20:58:18,138 [salt.loaded.ext.module.maasng:945 ][INFO    ][13522] [{u'id': 0, 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'name': u'untagged', 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': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-23 20:58:18,225 [salt.state       :300 ][INFO    ][13522] {'new': 'Vlan untagged was updated'}
2019-05-23 20:58:18,225 [salt.state       :1951][INFO    ][13522] Completed state [vlan 0] at time 20:58:18.225451 duration_in_ms=483.821
2019-05-23 20:58:18,227 [salt.state       :1780][INFO    ][13522] Running state [192.168.11.0/24] at time 20:58:18.227739
2019-05-23 20:58:18,228 [salt.state       :1813][INFO    ][13522] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-23 20:58:18,433 [salt.loaded.ext.module.maasng:945 ][INFO    ][13522] [{u'id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-23 20:58:18,436 [salt.loaded.ext.module.maasng:1235][WARNING ][13522] Ignoring parameter vlan:0
2019-05-23 20:58:18,526 [salt.state       :300 ][INFO    ][13522] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-23 20:58:18,526 [salt.state       :1951][INFO    ][13522] Completed state [192.168.11.0/24] at time 20:58:18.526527 duration_in_ms=298.788
2019-05-23 20:58:18,527 [salt.state       :1780][INFO    ][13522] Running state [maas_create_iprange_1] at time 20:58:18.527756
2019-05-23 20:58:18,528 [salt.state       :1813][INFO    ][13522] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-23 20:58:18,586 [salt.state       :300 ][INFO    ][13522] Iprange maas_create_iprange_1 already exist.
2019-05-23 20:58:18,587 [salt.state       :1951][INFO    ][13522] Completed state [maas_create_iprange_1] at time 20:58:18.587209 duration_in_ms=59.454
2019-05-23 20:58:18,587 [salt.state       :1780][INFO    ][13522] Running state [vlan 0] at time 20:58:18.587521
2019-05-23 20:58:18,587 [salt.state       :1813][INFO    ][13522] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-23 20:58:18,643 [salt.loaded.ext.module.maasng:945 ][INFO    ][13522] [{u'id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-23 20:58:18,741 [salt.loaded.ext.module.maasng:945 ][INFO    ][13522] [{u'id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-23 20:58:18,980 [salt.loaded.ext.module.maasng:945 ][INFO    ][13522] [{u'id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xetsmh', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-23 20:58:19,087 [salt.state       :300 ][INFO    ][13522] {'new': 'Vlan untagged was updated'}
2019-05-23 20:58:19,088 [salt.state       :1951][INFO    ][13522] Completed state [vlan 0] at time 20:58:19.088116 duration_in_ms=500.593
2019-05-23 20:58:19,089 [salt.state       :1780][INFO    ][13522] Running state [opnfv] at time 20:58:19.088961
2019-05-23 20:58:19,089 [salt.state       :1813][INFO    ][13522] Executing state maasng.sshkey_present for [opnfv]
2019-05-23 20:58:19,136 [salt.loaded.ext.module.maasng:1903][INFO    ][13522] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-23 20:58:19,136 [salt.state       :300 ][INFO    ][13522] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-23 20:58:19,136 [salt.state       :1951][INFO    ][13522] Completed state [opnfv] at time 20:58:19.136836 duration_in_ms=47.875
2019-05-23 20:58:19,141 [salt.minion      :1711][INFO    ][13522] Returning information for job: 20190523205755727152
2019-05-23 20:58:19,907 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523205819895437
2019-05-23 20:58:19,926 [salt.minion      :1432][INFO    ][13992] Starting a new job with PID 13992
2019-05-23 20:58:25,949 [salt.state       :915 ][INFO    ][13992] Loading fresh modules for state activity
2019-05-23 20:58:26,064 [salt.state       :1780][INFO    ][13992] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:58:26.064450
2019-05-23 20:58:26,064 [salt.state       :1813][INFO    ][13992] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 20:58:26,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13992] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 20:58:27,947 [salt.state       :300 ][INFO    ][13992] {'pid': 14015, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 20:58:27,948 [salt.state       :1951][INFO    ][13992] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:58:27.948076 duration_in_ms=1883.625
2019-05-23 20:58:27,951 [salt.state       :1780][INFO    ][13992] Running state [maas.process_machines] at time 20:58:27.951556
2019-05-23 20:58:27,952 [salt.state       :1813][INFO    ][13992] Executing state module.run for [maas.process_machines]
2019-05-23 20:58:27,953 [salt.utils.decorators:613 ][WARNING ][13992] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:58:28,552 [salt.loaded.ext.module.maas:412 ][WARNING ][13992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 20:58:28,554 [salt.loaded.ext.module.maas:92  ][INFO    ][13992] machine hostname=cmp002 power_type=ipmi mac_addresses=['9c:b6:54:8a:10:18'] power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 system_id=y4dhds architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-23 20:58:29,782 [salt.loaded.ext.module.maas:412 ][WARNING ][13992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 20:58:29,783 [salt.loaded.ext.module.maas:92  ][INFO    ][13992] machine hostname=cmp001 power_type=ipmi mac_addresses=['9c:b6:54:8a:95:a0'] power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 system_id=gh8w3r architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-23 20:58:31,028 [salt.loaded.ext.module.maas:412 ][WARNING ][13992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 20:58:31,029 [salt.loaded.ext.module.maas:92  ][INFO    ][13992] machine hostname=kvm01 power_type=ipmi mac_addresses=['14:58:d0:54:e7:88'] power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 system_id=rhcpy6 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-23 20:58:32,232 [salt.loaded.ext.module.maas:412 ][WARNING ][13992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 20:58:32,233 [salt.loaded.ext.module.maas:92  ][INFO    ][13992] machine hostname=kvm03 power_type=ipmi mac_addresses=['14:58:d0:54:7a:28'] power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 system_id=4tf67c architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-23 20:58:33,497 [salt.loaded.ext.module.maas:412 ][WARNING ][13992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-23 20:58:33,498 [salt.loaded.ext.module.maas:92  ][INFO    ][13992] machine hostname=kvm02 power_type=ipmi mac_addresses=['14:58:d0:54:6a:60'] power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 system_id=4rknnk architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-23 20:58:34,714 [salt.state       :300 ][INFO    ][13992] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-23 20:58:34,715 [salt.state       :1951][INFO    ][13992] Completed state [maas.process_machines] at time 20:58:34.715110 duration_in_ms=6763.553
2019-05-23 20:58:34,720 [salt.minion      :1711][INFO    ][13992] Returning information for job: 20190523205819895437
2019-05-23 20:59:08,512 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523205908497161
2019-05-23 20:59:08,531 [salt.minion      :1432][INFO    ][14284] Starting a new job with PID 14284
2019-05-23 20:59:14,463 [salt.state       :915 ][INFO    ][14284] Loading fresh modules for state activity
2019-05-23 20:59:14,578 [salt.state       :1780][INFO    ][14284] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:14.578208
2019-05-23 20:59:14,578 [salt.state       :1813][INFO    ][14284] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 20:59:14,582 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14284] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 20:59:16,459 [salt.state       :300 ][INFO    ][14284] {'pid': 14295, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 20:59:16,460 [salt.state       :1951][INFO    ][14284] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:16.460311 duration_in_ms=1882.105
2019-05-23 20:59:16,464 [salt.state       :1780][INFO    ][14284] Running state [maas.wait_for_machine_status] at time 20:59:16.464512
2019-05-23 20:59:16,465 [salt.state       :1813][INFO    ][14284] Executing state module.run for [maas.wait_for_machine_status]
2019-05-23 20:59:16,466 [salt.utils.decorators:613 ][WARNING ][14284] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:59:19,263 [salt.state       :300 ][INFO    ][14284] {'ret': True}
2019-05-23 20:59:19,263 [salt.state       :1951][INFO    ][14284] Completed state [maas.wait_for_machine_status] at time 20:59:19.263755 duration_in_ms=2799.243
2019-05-23 20:59:19,267 [salt.minion      :1711][INFO    ][14284] Returning information for job: 20190523205908497161
2019-05-23 20:59:20,015 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523205919952445
2019-05-23 20:59:20,045 [salt.minion      :1432][INFO    ][14310] Starting a new job with PID 14310
2019-05-23 20:59:21,031 [salt.state       :915 ][INFO    ][14310] Loading fresh modules for state activity
2019-05-23 20:59:21,192 [salt.state       :1780][INFO    ][14310] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:21.192310
2019-05-23 20:59:21,192 [salt.state       :1813][INFO    ][14310] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 20:59:21,195 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14310] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 20:59:23,044 [salt.state       :300 ][INFO    ][14310] {'pid': 14317, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 20:59:23,045 [salt.state       :1951][INFO    ][14310] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:23.045292 duration_in_ms=1852.982
2019-05-23 20:59:23,048 [salt.state       :1780][INFO    ][14310] Running state [maas_machines_storage_cmp002_lvm] at time 20:59:23.048289
2019-05-23 20:59:23,048 [salt.state       :1813][INFO    ][14310] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-23 20:59:23,659 [salt.state       :300 ][INFO    ][14310] Machine cmp002 is not in Ready state.
2019-05-23 20:59:23,660 [salt.state       :1951][INFO    ][14310] Completed state [maas_machines_storage_cmp002_lvm] at time 20:59:23.659989 duration_in_ms=611.699
2019-05-23 20:59:23,660 [salt.state       :1780][INFO    ][14310] Running state [maas_machines_storage_cmp001_lvm] at time 20:59:23.660483
2019-05-23 20:59:23,660 [salt.state       :1813][INFO    ][14310] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-23 20:59:24,181 [salt.state       :300 ][INFO    ][14310] Machine cmp001 is not in Ready state.
2019-05-23 20:59:24,183 [salt.state       :1951][INFO    ][14310] Completed state [maas_machines_storage_cmp001_lvm] at time 20:59:24.181682 duration_in_ms=521.199
2019-05-23 20:59:24,187 [salt.minion      :1711][INFO    ][14310] Returning information for job: 20190523205919952445
2019-05-23 20:59:24,890 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523205924877154
2019-05-23 20:59:24,916 [salt.minion      :1432][INFO    ][14327] Starting a new job with PID 14327
2019-05-23 20:59:25,923 [salt.state       :915 ][INFO    ][14327] Loading fresh modules for state activity
2019-05-23 20:59:26,038 [salt.state       :1780][INFO    ][14327] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:26.038686
2019-05-23 20:59:26,039 [salt.state       :1813][INFO    ][14327] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 20:59:26,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14327] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 20:59:27,920 [salt.state       :300 ][INFO    ][14327] {'pid': 14334, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 20:59:27,922 [salt.state       :1951][INFO    ][14327] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:27.921601 duration_in_ms=1882.915
2019-05-23 20:59:27,924 [salt.state       :1780][INFO    ][14327] Running state [maas.deploy_machines] at time 20:59:27.924789
2019-05-23 20:59:27,925 [salt.state       :1813][INFO    ][14327] Executing state module.run for [maas.deploy_machines]
2019-05-23 20:59:27,927 [salt.utils.decorators:613 ][WARNING ][14327] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:59:28,516 [salt.loaded.ext.module.maas:684 ][INFO    ][14327] deploymachines hwe_kernel=hwe-16.04 system_id=4rknnk distro_series=xenial
2019-05-23 20:59:31,071 [salt.state       :300 ][INFO    ][14327] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-23 20:59:31,072 [salt.state       :1951][INFO    ][14327] Completed state [maas.deploy_machines] at time 20:59:31.072121 duration_in_ms=3147.332
2019-05-23 20:59:31,076 [salt.minion      :1711][INFO    ][14327] Returning information for job: 20190523205924877154
2019-05-23 20:59:31,763 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command state.apply with jid 20190523205931751620
2019-05-23 20:59:31,788 [salt.minion      :1432][INFO    ][14400] Starting a new job with PID 14400
2019-05-23 20:59:37,733 [salt.state       :915 ][INFO    ][14400] Loading fresh modules for state activity
2019-05-23 20:59:37,860 [salt.state       :1780][INFO    ][14400] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:37.859910
2019-05-23 20:59:37,860 [salt.state       :1813][INFO    ][14400] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-23 20:59:37,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14400] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-23 20:59:39,683 [salt.state       :300 ][INFO    ][14400] {'pid': 14411, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-23 20:59:39,684 [salt.state       :1951][INFO    ][14400] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:59:39.684701 duration_in_ms=1824.793
2019-05-23 20:59:39,689 [salt.state       :1780][INFO    ][14400] Running state [maas.wait_for_machine_status] at time 20:59:39.689172
2019-05-23 20:59:39,690 [salt.state       :1813][INFO    ][14400] Executing state module.run for [maas.wait_for_machine_status]
2019-05-23 20:59:39,691 [salt.utils.decorators:613 ][WARNING ][14400] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-23 20:59:42,560 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.15337396s left)
2019-05-23 20:59:46,813 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523205946796085
2019-05-23 20:59:46,845 [salt.minion      :1432][INFO    ][14435] Starting a new job with PID 14435
2019-05-23 20:59:46,873 [salt.minion      :1711][INFO    ][14435] Returning information for job: 20190523205946796085
2019-05-23 21:00:15,287 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.426404s left)
2019-05-23 21:00:16,893 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210016882202
2019-05-23 21:00:16,919 [salt.minion      :1432][INFO    ][14487] Starting a new job with PID 14487
2019-05-23 21:00:16,952 [salt.minion      :1711][INFO    ][14487] Returning information for job: 20190523210016882202
2019-05-23 21:00:46,988 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210046972582
2019-05-23 21:00:47,009 [salt.minion      :1432][INFO    ][14520] Starting a new job with PID 14520
2019-05-23 21:00:47,041 [salt.minion      :1711][INFO    ][14520] Returning information for job: 20190523210046972582
2019-05-23 21:00:48,011 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.70237708s left)
2019-05-23 21:01:17,075 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210117067389
2019-05-23 21:01:17,095 [salt.minion      :1432][INFO    ][14581] Starting a new job with PID 14581
2019-05-23 21:01:17,124 [salt.minion      :1711][INFO    ][14581] Returning information for job: 20190523210117067389
2019-05-23 21:01:20,721 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.99188113s left)
2019-05-23 21:01:47,153 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210147139197
2019-05-23 21:01:47,183 [salt.minion      :1432][INFO    ][14612] Starting a new job with PID 14612
2019-05-23 21:01:47,217 [salt.minion      :1711][INFO    ][14612] Returning information for job: 20190523210147139197
2019-05-23 21:01:53,502 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.2110641s left)
2019-05-23 21:02:17,263 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210217248393
2019-05-23 21:02:17,298 [salt.minion      :1432][INFO    ][14668] Starting a new job with PID 14668
2019-05-23 21:02:17,344 [salt.minion      :1711][INFO    ][14668] Returning information for job: 20190523210217248393
2019-05-23 21:02:26,483 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.22972894s left)
2019-05-23 21:02:47,372 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210247363420
2019-05-23 21:02:47,396 [salt.minion      :1432][INFO    ][14694] Starting a new job with PID 14694
2019-05-23 21:02:47,428 [salt.minion      :1711][INFO    ][14694] Returning information for job: 20190523210247363420
2019-05-23 21:02:59,356 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.35703897s left)
2019-05-23 21:03:17,490 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210317474265
2019-05-23 21:03:17,514 [salt.minion      :1432][INFO    ][14740] Starting a new job with PID 14740
2019-05-23 21:03:17,545 [salt.minion      :1711][INFO    ][14740] Returning information for job: 20190523210317474265
2019-05-23 21:03:32,066 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.64670205s left)
2019-05-23 21:03:47,595 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210347581483
2019-05-23 21:03:47,615 [salt.minion      :1432][INFO    ][14794] Starting a new job with PID 14794
2019-05-23 21:03:47,645 [salt.minion      :1711][INFO    ][14794] Returning information for job: 20190523210347581483
2019-05-23 21:04:04,949 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.76443315s left)
2019-05-23 21:04:17,713 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210417699670
2019-05-23 21:04:17,742 [salt.minion      :1432][INFO    ][14869] Starting a new job with PID 14869
2019-05-23 21:04:17,780 [salt.minion      :1711][INFO    ][14869] Returning information for job: 20190523210417699670
2019-05-23 21:04:37,849 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.864398s left)
2019-05-23 21:04:47,830 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210447812748
2019-05-23 21:04:47,862 [salt.minion      :1432][INFO    ][14906] Starting a new job with PID 14906
2019-05-23 21:04:47,890 [salt.minion      :1711][INFO    ][14906] Returning information for job: 20190523210447812748
2019-05-23 21:05:10,825 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.88828516s left)
2019-05-23 21:05:17,984 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210517968632
2019-05-23 21:05:18,013 [salt.minion      :1432][INFO    ][15097] Starting a new job with PID 15097
2019-05-23 21:05:18,046 [salt.minion      :1711][INFO    ][15097] Returning information for job: 20190523210517968632
2019-05-23 21:05:43,642 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.07130814s left)
2019-05-23 21:05:48,108 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210548091448
2019-05-23 21:05:48,139 [salt.minion      :1432][INFO    ][15126] Starting a new job with PID 15126
2019-05-23 21:05:48,171 [salt.minion      :1711][INFO    ][15126] Returning information for job: 20190523210548091448
2019-05-23 21:06:16,602 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.11132097s left)
2019-05-23 21:06:18,247 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210618232154
2019-05-23 21:06:18,276 [salt.minion      :1432][INFO    ][15196] Starting a new job with PID 15196
2019-05-23 21:06:18,310 [salt.minion      :1711][INFO    ][15196] Returning information for job: 20190523210618232154
2019-05-23 21:06:48,394 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210648387516
2019-05-23 21:06:48,412 [salt.minion      :1432][INFO    ][15227] Starting a new job with PID 15227
2019-05-23 21:06:48,441 [salt.minion      :1711][INFO    ][15227] Returning information for job: 20190523210648387516
2019-05-23 21:06:49,558 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1820.15543604s left)
2019-05-23 21:07:18,559 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210718548210
2019-05-23 21:07:18,587 [salt.minion      :1432][INFO    ][15389] Starting a new job with PID 15389
2019-05-23 21:07:18,617 [salt.minion      :1711][INFO    ][15389] Returning information for job: 20190523210718548210
2019-05-23 21:07:22,463 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.24968314s left)
2019-05-23 21:07:48,718 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210748705176
2019-05-23 21:07:48,743 [salt.minion      :1432][INFO    ][15425] Starting a new job with PID 15425
2019-05-23 21:07:48,779 [salt.minion      :1711][INFO    ][15425] Returning information for job: 20190523210748705176
2019-05-23 21:07:55,172 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.54088998s left)
2019-05-23 21:08:18,881 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210818866525
2019-05-23 21:08:18,908 [salt.minion      :1432][INFO    ][15543] Starting a new job with PID 15543
2019-05-23 21:08:18,937 [salt.minion      :1711][INFO    ][15543] Returning information for job: 20190523210818866525
2019-05-23 21:08:28,352 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.36047006s left)
2019-05-23 21:08:49,042 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210849032526
2019-05-23 21:08:49,072 [salt.minion      :1432][INFO    ][15574] Starting a new job with PID 15574
2019-05-23 21:08:49,105 [salt.minion      :1711][INFO    ][15574] Returning information for job: 20190523210849032526
2019-05-23 21:09:01,483 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1688.22943902s left)
2019-05-23 21:09:19,212 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210919199101
2019-05-23 21:09:19,241 [salt.minion      :1432][INFO    ][15620] Starting a new job with PID 15620
2019-05-23 21:09:19,280 [salt.minion      :1711][INFO    ][15620] Returning information for job: 20190523210919199101
2019-05-23 21:09:34,333 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1655.38003302s left)
2019-05-23 21:09:49,415 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523210949402972
2019-05-23 21:09:49,445 [salt.minion      :1432][INFO    ][15650] Starting a new job with PID 15650
2019-05-23 21:09:49,473 [salt.minion      :1711][INFO    ][15650] Returning information for job: 20190523210949402972
2019-05-23 21:10:07,127 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1622.58580709s left)
2019-05-23 21:10:19,603 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523211019587223
2019-05-23 21:10:19,636 [salt.minion      :1432][INFO    ][15699] Starting a new job with PID 15699
2019-05-23 21:10:19,669 [salt.minion      :1711][INFO    ][15699] Returning information for job: 20190523211019587223
2019-05-23 21:10:39,959 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1589.75432205s left)
2019-05-23 21:10:49,806 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523211049791920
2019-05-23 21:10:49,836 [salt.minion      :1432][INFO    ][15735] Starting a new job with PID 15735
2019-05-23 21:10:49,865 [salt.minion      :1711][INFO    ][15735] Returning information for job: 20190523211049791920
2019-05-23 21:11:12,993 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.72046804s left)
2019-05-23 21:11:19,993 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523211119980366
2019-05-23 21:11:20,019 [salt.minion      :1432][INFO    ][15945] Starting a new job with PID 15945
2019-05-23 21:11:20,055 [salt.minion      :1711][INFO    ][15945] Returning information for job: 20190523211119980366
2019-05-23 21:11:45,988 [salt.loaded.ext.module.maas:1023][INFO    ][14400] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.72469997s left)
2019-05-23 21:11:48,054 [salt.utils.schedule:1377][INFO    ][5118] Running scheduled job: __mine_interval
2019-05-23 21:11:50,216 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command saltutil.find_job with jid 20190523211150202621
2019-05-23 21:11:50,240 [salt.minion      :1432][INFO    ][16013] Starting a new job with PID 16013
2019-05-23 21:11:50,272 [salt.minion      :1711][INFO    ][16013] Returning information for job: 20190523211150202621
2019-05-23 21:12:18,893 [salt.state       :300 ][INFO    ][14400] {'ret': True}
2019-05-23 21:12:18,894 [salt.state       :1951][INFO    ][14400] Completed state [maas.wait_for_machine_status] at time 21:12:18.894084 duration_in_ms=759204.91
2019-05-23 21:12:18,899 [salt.minion      :1711][INFO    ][14400] Returning information for job: 20190523205931751620
2019-05-23 22:11:48,054 [salt.utils.schedule:1377][INFO    ][5118] Running scheduled job: __mine_interval
2019-05-23 22:35:02,813 [salt.minion      :1308][INFO    ][5118] User sudo_ubuntu Executing command cp.push_dir with jid 20190523223502803595
2019-05-23 22:35:02,838 [salt.minion      :1432][INFO    ][22007] Starting a new job with PID 22007
