2019-06-14 02:06:40,401 [salt.utils.decorators:613 ][WARNING ][1971] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:06:41,470 [salt.utils.decorators:613 ][WARNING ][1971] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:06:45,109 [salt.loaded.int.states.file:2298][WARNING ][2470] 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-06-14 02:07:15,244 [salt.state       :2022][WARNING ][2863] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-14 02:07:18,496 [salt.utils.decorators:613 ][WARNING ][2863] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:07:34,356 [salt.utils.decorators:613 ][WARNING ][2863] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:08:01,387 [salt.utils.decorators:613 ][WARNING ][2863] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:08:02,586 [salt.utils.decorators:613 ][WARNING ][2863] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:08:05,231 [salt.loaded.ext.module.maasng:1008][WARNING ][2863] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-14 02:08:05,232 [salt.loaded.ext.module.maasng:1011][WARNING ][2863] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-14 02:08:05,994 [salt.loaded.ext.module.maasng:1235][WARNING ][2863] Ignoring parameter vlan:0
2019-06-14 02:08:10,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5367] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-14 02:08:10,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5367] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-14 02:08:10,458 [salt.utils.parsers:1051][WARNING ][388] Minion received a SIGTERM. Exiting.
2019-06-14 02:08:11,732 [salt.cli.daemons :293 ][INFO    ][5435] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-06-14 02:08:11,908 [salt.cli.daemons :82  ][INFO    ][5435] Starting up the Salt Minion
2019-06-14 02:08:11,909 [salt.utils.event :1017][INFO    ][5435] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-06-14 02:08:13,150 [salt.minion      :976 ][INFO    ][5435] Creating minion process manager
2019-06-14 02:08:15,203 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5435] Executing command ['date', '+%z'] in directory '/root'
2019-06-14 02:08:15,229 [salt.utils.schedule:568 ][INFO    ][5435] Updating job settings for scheduled job: __mine_interval
2019-06-14 02:08:15,233 [salt.minion      :1108][INFO    ][5435] Added mine.update to scheduler
2019-06-14 02:08:15,237 [salt.minion      :1975][INFO    ][5435] Minion is starting as user 'root'
2019-06-14 02:08:15,251 [salt.minion      :2336][INFO    ][5435] Minion is ready to receive requests!
2019-06-14 02:08:18,349 [salt.utils.decorators:613 ][WARNING ][5372] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:08:18,427 [salt.loaded.ext.module.maas:412 ][WARNING ][5372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-14 02:08:20,123 [salt.loaded.ext.module.maas:412 ][WARNING ][5372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-14 02:08:21,741 [salt.loaded.ext.module.maas:412 ][WARNING ][5372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-14 02:08:22,855 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614020822841057
2019-06-14 02:08:22,884 [salt.minion      :1432][INFO    ][5704] Starting a new job with PID 5704
2019-06-14 02:08:22,911 [salt.minion      :1711][INFO    ][5704] Returning information for job: 20190614020822841057
2019-06-14 02:08:23,238 [salt.loaded.ext.module.maas:412 ][WARNING ][5372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-14 02:08:24,692 [salt.loaded.ext.module.maas:412 ][WARNING ][5372] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-14 02:08:57,560 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614020857547679
2019-06-14 02:08:57,587 [salt.minion      :1432][INFO    ][5857] Starting a new job with PID 5857
2019-06-14 02:09:05,687 [salt.state       :915 ][INFO    ][5857] Loading fresh modules for state activity
2019-06-14 02:09:05,756 [salt.fileclient  :1219][INFO    ][5857] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-14 02:09:05,820 [salt.state       :1780][INFO    ][5857] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:05.820187
2019-06-14 02:09:05,820 [salt.state       :1813][INFO    ][5857] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-14 02:09:05,823 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5857] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-14 02:09:07,734 [salt.state       :300 ][INFO    ][5857] {'pid': 5876, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-14 02:09:07,736 [salt.state       :1951][INFO    ][5857] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:07.736015 duration_in_ms=1915.828
2019-06-14 02:09:07,740 [salt.state       :1780][INFO    ][5857] Running state [maas.wait_for_machine_status] at time 02:09:07.740147
2019-06-14 02:09:07,741 [salt.state       :1813][INFO    ][5857] Executing state module.run for [maas.wait_for_machine_status]
2019-06-14 02:09:07,743 [salt.utils.decorators:613 ][WARNING ][5857] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:09:08,599 [salt.loaded.ext.module.maas:1023][INFO    ][5857] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.15958095s left)
2019-06-14 02:09:12,669 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614020912654732
2019-06-14 02:09:12,700 [salt.minion      :1432][INFO    ][5888] Starting a new job with PID 5888
2019-06-14 02:09:12,728 [salt.minion      :1711][INFO    ][5888] Returning information for job: 20190614020912654732
2019-06-14 02:09:39,433 [salt.loaded.ext.module.maas:1023][INFO    ][5857] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.32628798s left)
2019-06-14 02:09:42,769 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614020942751759
2019-06-14 02:09:42,796 [salt.minion      :1432][INFO    ][5932] Starting a new job with PID 5932
2019-06-14 02:09:42,826 [salt.minion      :1711][INFO    ][5932] Returning information for job: 20190614020942751759
2019-06-14 02:10:10,287 [salt.loaded.ext.module.maas:1023][INFO    ][5857] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.47205091s left)
2019-06-14 02:10:12,884 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021012869498
2019-06-14 02:10:12,913 [salt.minion      :1432][INFO    ][5959] Starting a new job with PID 5959
2019-06-14 02:10:12,943 [salt.minion      :1711][INFO    ][5959] Returning information for job: 20190614021012869498
2019-06-14 02:10:41,153 [salt.loaded.ext.module.maas:1023][INFO    ][5857] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.60641909s left)
2019-06-14 02:10:42,979 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021042963557
2019-06-14 02:10:43,008 [salt.minion      :1432][INFO    ][6007] Starting a new job with PID 6007
2019-06-14 02:10:43,034 [salt.minion      :1711][INFO    ][6007] Returning information for job: 20190614021042963557
2019-06-14 02:11:12,255 [salt.loaded.ext.module.maas:1023][INFO    ][5857] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.50439095s left)
2019-06-14 02:11:13,055 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021113039112
2019-06-14 02:11:13,077 [salt.minion      :1432][INFO    ][6068] Starting a new job with PID 6068
2019-06-14 02:11:13,105 [salt.minion      :1711][INFO    ][6068] Returning information for job: 20190614021113039112
2019-06-14 02:11:43,151 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021143138919
2019-06-14 02:11:43,182 [salt.minion      :1432][INFO    ][6223] Starting a new job with PID 6223
2019-06-14 02:11:43,209 [salt.minion      :1711][INFO    ][6223] Returning information for job: 20190614021143138919
2019-06-14 02:11:43,375 [salt.loaded.ext.module.maas:1023][INFO    ][5857] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.38365412s left)
2019-06-14 02:12:13,269 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021213258929
2019-06-14 02:12:13,293 [salt.minion      :1432][INFO    ][6323] Starting a new job with PID 6323
2019-06-14 02:12:13,324 [salt.minion      :1711][INFO    ][6323] Returning information for job: 20190614021213258929
2019-06-14 02:12:14,731 [salt.loaded.ext.module.maas:1023][INFO    ][5857] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.028229s left)
2019-06-14 02:12:43,411 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021243397871
2019-06-14 02:12:43,433 [salt.minion      :1432][INFO    ][6653] Starting a new job with PID 6653
2019-06-14 02:12:43,459 [salt.minion      :1711][INFO    ][6653] Returning information for job: 20190614021243397871
2019-06-14 02:12:46,160 [salt.loaded.ext.module.maas:1023][INFO    ][5857] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.598701s left)
2019-06-14 02:13:13,534 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021313522440
2019-06-14 02:13:13,559 [salt.minion      :1432][INFO    ][6772] Starting a new job with PID 6772
2019-06-14 02:13:13,588 [salt.minion      :1711][INFO    ][6772] Returning information for job: 20190614021313522440
2019-06-14 02:13:18,212 [salt.loaded.ext.module.maas:1023][INFO    ][5857] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.54713798s left)
2019-06-14 02:13:43,736 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021343719834
2019-06-14 02:13:43,758 [salt.minion      :1432][INFO    ][7237] Starting a new job with PID 7237
2019-06-14 02:13:43,785 [salt.minion      :1711][INFO    ][7237] Returning information for job: 20190614021343719834
2019-06-14 02:13:50,396 [salt.loaded.ext.module.maas:1023][INFO    ][5857] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.36343908s left)
2019-06-14 02:14:13,849 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021413834157
2019-06-14 02:14:13,876 [salt.minion      :1432][INFO    ][7289] Starting a new job with PID 7289
2019-06-14 02:14:13,908 [salt.minion      :1711][INFO    ][7289] Returning information for job: 20190614021413834157
2019-06-14 02:14:23,023 [salt.loaded.ext.module.maas:1023][INFO    ][5857] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.73613811s left)
2019-06-14 02:14:44,009 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021443995161
2019-06-14 02:14:44,034 [salt.minion      :1432][INFO    ][7523] Starting a new job with PID 7523
2019-06-14 02:14:44,060 [salt.minion      :1711][INFO    ][7523] Returning information for job: 20190614021443995161
2019-06-14 02:14:55,942 [salt.state       :300 ][INFO    ][5857] {'ret': True}
2019-06-14 02:14:55,942 [salt.state       :1951][INFO    ][5857] Completed state [maas.wait_for_machine_status] at time 02:14:55.942700 duration_in_ms=348202.552
2019-06-14 02:14:55,948 [salt.minion      :1711][INFO    ][5857] Returning information for job: 20190614020857547679
2019-06-14 02:14:56,665 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614021456655930
2019-06-14 02:14:56,688 [salt.minion      :1432][INFO    ][7564] Starting a new job with PID 7564
2019-06-14 02:15:04,870 [salt.state       :915 ][INFO    ][7564] Loading fresh modules for state activity
2019-06-14 02:15:04,925 [salt.fileclient  :1219][INFO    ][7564] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-14 02:15:05,037 [salt.state       :1780][INFO    ][7564] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:05.037599
2019-06-14 02:15:05,038 [salt.state       :1813][INFO    ][7564] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-14 02:15:05,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7564] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-14 02:15:06,931 [salt.state       :300 ][INFO    ][7564] {'pid': 7586, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-14 02:15:06,932 [salt.state       :1951][INFO    ][7564] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:06.932186 duration_in_ms=1894.587
2019-06-14 02:15:06,936 [salt.state       :1780][INFO    ][7564] Running state [maas_machines_storage_cmp002_lvm] at time 02:15:06.935964
2019-06-14 02:15:06,936 [salt.state       :1813][INFO    ][7564] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-14 02:15:08,201 [salt.loaded.ext.module.maasng:610 ][INFO    ][7564] w8akqy
2019-06-14 02:15:08,202 [salt.loaded.ext.module.maasng:626 ][INFO    ][7564] sda
2019-06-14 02:15:08,768 [salt.loaded.ext.module.maasng:361 ][INFO    ][7564] w8akqy
2019-06-14 02:15:08,859 [salt.loaded.ext.module.maasng:367 ][INFO    ][7564] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'3a9d475e-f44c-411a-8965-a6ec3cca2135', u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w8akqy', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'9b253e84-0f27-481b-8e81-27ad5fcbf8e6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 1, u'size': 800101236736}], u'name': u'sda', u'system_id': u'w8akqy', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/blockdevices/1/'}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'8bf22314-c3c3-446d-a1a3-7cdb30c4ba66', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'w8akqy', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'64740b0e-1c9d-4069-ac44-597e5ea06aa0', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 800097042432, u'used_size': 800097042432, u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/blockdevices/3/'}]
2019-06-14 02:15:08,859 [salt.loaded.ext.module.maasng:632 ][INFO    ][7564] vgroot
2019-06-14 02:15:08,859 [salt.loaded.ext.module.maasng:635 ][INFO    ][7564] lvroot
2019-06-14 02:15:08,860 [salt.loaded.ext.module.maasng:639 ][INFO    ][7564] 107374182400
2019-06-14 02:15:09,455 [salt.loaded.ext.module.maasng:645 ][INFO    ][7564] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 4, u'effective_mtu': 1500, u'system_id': u'w8akqy', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/interfaces/4/'}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'47d7637b-375b-4d22-af0f-1ffd2db5092c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w8akqy', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'576b2f2d-c76f-4201-a2a0-3f52641bb9fb', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'w8akqy', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'uuid': None}, {u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'w8akqy', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'b5d9682a-9df6-41ec-ac01-724fd86a54e6', u'fstype': u'ext4'}, u'id': 11, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/blockdevices/11/', u'used_size': 107374182400, u'model': None, u'uuid': u'2054763b-9d6e-4f34-8c3f-fd175bc03a09'}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/w8akqy/', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'w8akqy', 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'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'w8akqy', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'b5d9682a-9df6-41ec-ac01-724fd86a54e6', u'fstype': u'ext4'}, u'id': 11, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/blockdevices/11/', u'used_size': 107374182400, u'model': None, u'uuid': u'2054763b-9d6e-4f34-8c3f-fd175bc03a09'}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'w8akqy', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'47d7637b-375b-4d22-af0f-1ffd2db5092c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w8akqy', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'576b2f2d-c76f-4201-a2a0-3f52641bb9fb', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'w8akqy', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'uuid': None}, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 4, u'effective_mtu': 1500, u'system_id': u'w8akqy', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/interfaces/4/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 14, u'effective_mtu': 1500, u'system_id': u'w8akqy', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/interfaces/14/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 15, u'effective_mtu': 1500, u'system_id': u'w8akqy', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/interfaces/15/'}, {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': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 13, u'effective_mtu': 1500, u'system_id': u'w8akqy', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/interfaces/13/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'fabric': u'fabric-0'}, u'enabled': True, u'id': 11, u'effective_mtu': 1500, u'system_id': u'w8akqy', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/interfaces/11/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'fabric': u'fabric-0'}, u'enabled': True, u'id': 12, u'effective_mtu': 1500, u'system_id': u'w8akqy', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/interfaces/12/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'47d7637b-375b-4d22-af0f-1ffd2db5092c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w8akqy', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'576b2f2d-c76f-4201-a2a0-3f52641bb9fb', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'system_id': u'w8akqy', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/w8akqy/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'uuid': None}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-06-14 02:15:09,459 [salt.state       :300 ][INFO    ][7564] {'new': {'storage_layout': 'lvm'}}
2019-06-14 02:15:09,460 [salt.state       :1951][INFO    ][7564] Completed state [maas_machines_storage_cmp002_lvm] at time 02:15:09.460272 duration_in_ms=2524.305
2019-06-14 02:15:09,461 [salt.state       :1780][INFO    ][7564] Running state [maas_machines_storage_cmp001_lvm] at time 02:15:09.460993
2019-06-14 02:15:09,461 [salt.state       :1813][INFO    ][7564] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-14 02:15:10,632 [salt.loaded.ext.module.maasng:610 ][INFO    ][7564] wrgcf3
2019-06-14 02:15:10,632 [salt.loaded.ext.module.maasng:626 ][INFO    ][7564] sda
2019-06-14 02:15:11,221 [salt.loaded.ext.module.maasng:361 ][INFO    ][7564] wrgcf3
2019-06-14 02:15:11,318 [salt.loaded.ext.module.maasng:367 ][INFO    ][7564] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'eef1ab15-31a8-4c27-bc76-8cf424e3facb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wrgcf3', u'device_id': 2, u'filesystem': {u'uuid': u'5f785e6b-6a60-45e6-a12d-bc43e83e4d51', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wrgcf3', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/blockdevices/2/', u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'uuid': u'c990f86b-17ff-4465-a167-e0b921a22ca4', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'c1443fee-94af-4e12-a2e9-c5646182ca84', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wrgcf3', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/blockdevices/4/', u'type': u'virtual', u'id': 4, u'size': 800097042432}]
2019-06-14 02:15:11,318 [salt.loaded.ext.module.maasng:632 ][INFO    ][7564] vgroot
2019-06-14 02:15:11,319 [salt.loaded.ext.module.maasng:635 ][INFO    ][7564] lvroot
2019-06-14 02:15:11,319 [salt.loaded.ext.module.maasng:639 ][INFO    ][7564] 107374182400
2019-06-14 02:15:11,734 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021511721048
2019-06-14 02:15:11,753 [salt.minion      :1432][INFO    ][7617] Starting a new job with PID 7617
2019-06-14 02:15:11,782 [salt.minion      :1711][INFO    ][7617] Returning information for job: 20190614021511721048
2019-06-14 02:15:11,941 [salt.loaded.ext.module.maasng:645 ][INFO    ][7564] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'wrgcf3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'9334d000-c675-4627-b35d-458ea02c1559', u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wrgcf3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bcc610ec-42af-46fe-b61c-bc53295fea9d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'system_id': u'wrgcf3', 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'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/blockdevices/2/'}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'b907ad92-7832-4ce2-8dcb-e2cf4ab3b070', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'wrgcf3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'12b0fa55-f51a-4b84-a809-18813fd607a3', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/blockdevices/12/'}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/wrgcf3/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'wrgcf3', 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'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'9334d000-c675-4627-b35d-458ea02c1559', u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wrgcf3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bcc610ec-42af-46fe-b61c-bc53295fea9d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'system_id': u'wrgcf3', 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'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'wrgcf3', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'b907ad92-7832-4ce2-8dcb-e2cf4ab3b070', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'wrgcf3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'12b0fa55-f51a-4b84-a809-18813fd607a3', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', 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': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'system_id': u'wrgcf3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'system_id': u'wrgcf3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/interfaces/16/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'system_id': u'wrgcf3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/interfaces/20/'}, {u'name': u'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'system_id': u'wrgcf3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/interfaces/17/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'system_id': u'wrgcf3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/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': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'id': 19, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'system_id': u'wrgcf3', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'9334d000-c675-4627-b35d-458ea02c1559', u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wrgcf3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'bcc610ec-42af-46fe-b61c-bc53295fea9d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'system_id': u'wrgcf3', 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'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/wrgcf3/blockdevices/2/'}}
2019-06-14 02:15:11,944 [salt.state       :300 ][INFO    ][7564] {'new': {'storage_layout': 'lvm'}}
2019-06-14 02:15:11,945 [salt.state       :1951][INFO    ][7564] Completed state [maas_machines_storage_cmp001_lvm] at time 02:15:11.944911 duration_in_ms=2483.918
2019-06-14 02:15:11,948 [salt.minion      :1711][INFO    ][7564] Returning information for job: 20190614021456655930
2019-06-14 02:15:12,659 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614021512645783
2019-06-14 02:15:12,682 [salt.minion      :1432][INFO    ][7624] Starting a new job with PID 7624
2019-06-14 02:15:13,875 [salt.state       :915 ][INFO    ][7624] Loading fresh modules for state activity
2019-06-14 02:15:13,937 [salt.fileclient  :1219][INFO    ][7624] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-14 02:15:13,999 [salt.state       :1780][INFO    ][7624] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:13.999048
2019-06-14 02:15:13,999 [salt.state       :1813][INFO    ][7624] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-14 02:15:14,001 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7624] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-14 02:15:15,956 [salt.state       :300 ][INFO    ][7624] {'pid': 7631, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-14 02:15:15,957 [salt.state       :1951][INFO    ][7624] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:15.956973 duration_in_ms=1957.926
2019-06-14 02:15:15,960 [salt.state       :1780][INFO    ][7624] Running state [maas.deploy_machines] at time 02:15:15.960550
2019-06-14 02:15:15,961 [salt.state       :1813][INFO    ][7624] Executing state module.run for [maas.deploy_machines]
2019-06-14 02:15:15,963 [salt.utils.decorators:613 ][WARNING ][7624] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:15:16,596 [salt.loaded.ext.module.maas:684 ][INFO    ][7624] deploymachines hwe_kernel=ga-16.04 system_id=w8akqy distro_series=xenial
2019-06-14 02:15:19,139 [salt.loaded.ext.module.maas:684 ][INFO    ][7624] deploymachines hwe_kernel=ga-16.04 system_id=wrgcf3 distro_series=xenial
2019-06-14 02:15:21,718 [salt.loaded.ext.module.maas:684 ][INFO    ][7624] deploymachines hwe_kernel=ga-16.04 system_id=p4edbt distro_series=xenial
2019-06-14 02:15:24,280 [salt.loaded.ext.module.maas:684 ][INFO    ][7624] deploymachines hwe_kernel=ga-16.04 system_id=hxxbg4 distro_series=xenial
2019-06-14 02:15:26,818 [salt.loaded.ext.module.maas:684 ][INFO    ][7624] deploymachines hwe_kernel=ga-16.04 system_id=cgbbg3 distro_series=xenial
2019-06-14 02:15:27,728 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021527715498
2019-06-14 02:15:27,755 [salt.minion      :1432][INFO    ][7898] Starting a new job with PID 7898
2019-06-14 02:15:27,786 [salt.minion      :1711][INFO    ][7898] Returning information for job: 20190614021527715498
2019-06-14 02:15:29,480 [salt.state       :300 ][INFO    ][7624] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-14 02:15:29,480 [salt.state       :1951][INFO    ][7624] Completed state [maas.deploy_machines] at time 02:15:29.480365 duration_in_ms=13519.815
2019-06-14 02:15:29,484 [salt.minion      :1711][INFO    ][7624] Returning information for job: 20190614021512645783
2019-06-14 02:15:30,067 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614021530054541
2019-06-14 02:15:30,092 [salt.minion      :1432][INFO    ][7937] Starting a new job with PID 7937
2019-06-14 02:15:38,208 [salt.state       :915 ][INFO    ][7937] Loading fresh modules for state activity
2019-06-14 02:15:38,273 [salt.fileclient  :1219][INFO    ][7937] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-14 02:15:38,327 [salt.state       :1780][INFO    ][7937] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:38.327165
2019-06-14 02:15:38,327 [salt.state       :1813][INFO    ][7937] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-14 02:15:38,329 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7937] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-14 02:15:40,186 [salt.state       :300 ][INFO    ][7937] {'pid': 7961, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-14 02:15:40,187 [salt.state       :1951][INFO    ][7937] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:40.187258 duration_in_ms=1860.092
2019-06-14 02:15:40,191 [salt.state       :1780][INFO    ][7937] Running state [maas.wait_for_machine_status] at time 02:15:40.191528
2019-06-14 02:15:40,191 [salt.state       :1813][INFO    ][7937] Executing state module.run for [maas.wait_for_machine_status]
2019-06-14 02:15:40,192 [salt.utils.decorators:613 ][WARNING ][7937] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:15:43,247 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.95272398s left)
2019-06-14 02:15:45,197 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021545178215
2019-06-14 02:15:45,223 [salt.minion      :1432][INFO    ][7976] Starting a new job with PID 7976
2019-06-14 02:15:45,244 [salt.minion      :1711][INFO    ][7976] Returning information for job: 20190614021545178215
2019-06-14 02:16:15,261 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021615251110
2019-06-14 02:16:15,284 [salt.minion      :1432][INFO    ][8012] Starting a new job with PID 8012
2019-06-14 02:16:15,306 [salt.minion      :1711][INFO    ][8012] Returning information for job: 20190614021615251110
2019-06-14 02:16:16,073 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.12646103s left)
2019-06-14 02:16:45,345 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021645334156
2019-06-14 02:16:45,377 [salt.minion      :1432][INFO    ][8062] Starting a new job with PID 8062
2019-06-14 02:16:45,399 [salt.minion      :1711][INFO    ][8062] Returning information for job: 20190614021645334156
2019-06-14 02:16:49,325 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.87524891s left)
2019-06-14 02:17:15,419 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021715403058
2019-06-14 02:17:15,448 [salt.minion      :1432][INFO    ][8107] Starting a new job with PID 8107
2019-06-14 02:17:15,469 [salt.minion      :1711][INFO    ][8107] Returning information for job: 20190614021715403058
2019-06-14 02:17:22,228 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.97172403s left)
2019-06-14 02:17:45,523 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021745507596
2019-06-14 02:17:45,551 [salt.minion      :1432][INFO    ][8162] Starting a new job with PID 8162
2019-06-14 02:17:45,571 [salt.minion      :1711][INFO    ][8162] Returning information for job: 20190614021745507596
2019-06-14 02:17:55,037 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.1626029s left)
2019-06-14 02:18:15,600 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021815587098
2019-06-14 02:18:15,629 [salt.minion      :1432][INFO    ][8210] Starting a new job with PID 8210
2019-06-14 02:18:15,649 [salt.minion      :1711][INFO    ][8210] Returning information for job: 20190614021815587098
2019-06-14 02:18:28,434 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.76631808s left)
2019-06-14 02:18:45,688 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021845668724
2019-06-14 02:18:45,715 [salt.minion      :1432][INFO    ][8309] Starting a new job with PID 8309
2019-06-14 02:18:45,736 [salt.minion      :1711][INFO    ][8309] Returning information for job: 20190614021845668724
2019-06-14 02:19:01,609 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.5909431s left)
2019-06-14 02:19:15,776 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021915761846
2019-06-14 02:19:15,804 [salt.minion      :1432][INFO    ][8377] Starting a new job with PID 8377
2019-06-14 02:19:15,824 [salt.minion      :1711][INFO    ][8377] Returning information for job: 20190614021915761846
2019-06-14 02:19:34,672 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.5275979s left)
2019-06-14 02:19:45,897 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614021945888295
2019-06-14 02:19:45,921 [salt.minion      :1432][INFO    ][8718] Starting a new job with PID 8718
2019-06-14 02:19:45,958 [salt.minion      :1711][INFO    ][8718] Returning information for job: 20190614021945888295
2019-06-14 02:20:07,732 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.46763992s left)
2019-06-14 02:20:15,992 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022015982777
2019-06-14 02:20:16,013 [salt.minion      :1432][INFO    ][8805] Starting a new job with PID 8805
2019-06-14 02:20:16,039 [salt.minion      :1711][INFO    ][8805] Returning information for job: 20190614022015982777
2019-06-14 02:20:40,500 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.70037007s left)
2019-06-14 02:20:46,123 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022046105049
2019-06-14 02:20:46,151 [salt.minion      :1432][INFO    ][9046] Starting a new job with PID 9046
2019-06-14 02:20:46,172 [salt.minion      :1711][INFO    ][9046] Returning information for job: 20190614022046105049
2019-06-14 02:21:13,672 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.52773905s left)
2019-06-14 02:21:16,253 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022116241822
2019-06-14 02:21:16,276 [salt.minion      :1432][INFO    ][9094] Starting a new job with PID 9094
2019-06-14 02:21:16,306 [salt.minion      :1711][INFO    ][9094] Returning information for job: 20190614022116241822
2019-06-14 02:21:46,435 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022146421201
2019-06-14 02:21:46,458 [salt.minion      :1432][INFO    ][9511] Starting a new job with PID 9511
2019-06-14 02:21:46,482 [salt.minion      :1711][INFO    ][9511] Returning information for job: 20190614022146421201
2019-06-14 02:21:46,836 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.36370301s left)
2019-06-14 02:22:16,565 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022216553376
2019-06-14 02:22:16,590 [salt.minion      :1432][INFO    ][9559] Starting a new job with PID 9559
2019-06-14 02:22:16,613 [salt.minion      :1711][INFO    ][9559] Returning information for job: 20190614022216553376
2019-06-14 02:22:20,725 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1849.47511292s left)
2019-06-14 02:22:46,765 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022246755082
2019-06-14 02:22:46,792 [salt.minion      :1432][INFO    ][9995] Starting a new job with PID 9995
2019-06-14 02:22:46,812 [salt.minion      :1711][INFO    ][9995] Returning information for job: 20190614022246755082
2019-06-14 02:22:53,608 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.59181094s left)
2019-06-14 02:23:16,912 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022316895959
2019-06-14 02:23:16,941 [salt.minion      :1432][INFO    ][10026] Starting a new job with PID 10026
2019-06-14 02:23:16,961 [salt.minion      :1711][INFO    ][10026] Returning information for job: 20190614022316895959
2019-06-14 02:23:26,682 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.517869s left)
2019-06-14 02:23:47,075 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022347061317
2019-06-14 02:23:47,099 [salt.minion      :1432][INFO    ][10209] Starting a new job with PID 10209
2019-06-14 02:23:47,121 [salt.minion      :1711][INFO    ][10209] Returning information for job: 20190614022347061317
2019-06-14 02:23:59,880 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.31944299s left)
2019-06-14 02:24:17,227 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022417215095
2019-06-14 02:24:17,254 [salt.minion      :1432][INFO    ][10244] Starting a new job with PID 10244
2019-06-14 02:24:17,279 [salt.minion      :1711][INFO    ][10244] Returning information for job: 20190614022417215095
2019-06-14 02:24:32,770 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.43034792s left)
2019-06-14 02:24:47,408 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022447396421
2019-06-14 02:24:47,433 [salt.minion      :1432][INFO    ][10444] Starting a new job with PID 10444
2019-06-14 02:24:47,454 [salt.minion      :1711][INFO    ][10444] Returning information for job: 20190614022447396421
2019-06-14 02:25:05,673 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.52697301s left)
2019-06-14 02:25:17,596 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022517583077
2019-06-14 02:25:17,616 [salt.minion      :1432][INFO    ][10510] Starting a new job with PID 10510
2019-06-14 02:25:17,637 [salt.minion      :1711][INFO    ][10510] Returning information for job: 20190614022517583077
2019-06-14 02:25:38,526 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.67431498s left)
2019-06-14 02:25:47,815 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022547803099
2019-06-14 02:25:47,838 [salt.minion      :1432][INFO    ][10747] Starting a new job with PID 10747
2019-06-14 02:25:47,860 [salt.minion      :1711][INFO    ][10747] Returning information for job: 20190614022547803099
2019-06-14 02:26:11,496 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.70434809s left)
2019-06-14 02:26:17,986 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022617971221
2019-06-14 02:26:18,017 [salt.minion      :1432][INFO    ][10776] Starting a new job with PID 10776
2019-06-14 02:26:18,037 [salt.minion      :1711][INFO    ][10776] Returning information for job: 20190614022617971221
2019-06-14 02:26:44,635 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.56466007s left)
2019-06-14 02:26:48,175 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022648159757
2019-06-14 02:26:48,203 [salt.minion      :1432][INFO    ][10901] Starting a new job with PID 10901
2019-06-14 02:26:48,228 [salt.minion      :1711][INFO    ][10901] Returning information for job: 20190614022648159757
2019-06-14 02:27:17,656 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.54396391s left)
2019-06-14 02:27:18,369 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022718352546
2019-06-14 02:27:18,400 [salt.minion      :1432][INFO    ][11068] Starting a new job with PID 11068
2019-06-14 02:27:18,422 [salt.minion      :1711][INFO    ][11068] Returning information for job: 20190614022718352546
2019-06-14 02:27:48,572 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022748561286
2019-06-14 02:27:48,594 [salt.minion      :1432][INFO    ][11165] Starting a new job with PID 11165
2019-06-14 02:27:48,619 [salt.minion      :1711][INFO    ][11165] Returning information for job: 20190614022748561286
2019-06-14 02:27:50,537 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1519.66284895s left)
2019-06-14 02:28:18,764 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022818750587
2019-06-14 02:28:18,793 [salt.minion      :1432][INFO    ][11255] Starting a new job with PID 11255
2019-06-14 02:28:18,828 [salt.minion      :1711][INFO    ][11255] Returning information for job: 20190614022818750587
2019-06-14 02:28:23,882 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1486.31794405s left)
2019-06-14 02:28:48,811 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022848796366
2019-06-14 02:28:48,839 [salt.minion      :1432][INFO    ][11472] Starting a new job with PID 11472
2019-06-14 02:28:48,861 [salt.minion      :1711][INFO    ][11472] Returning information for job: 20190614022848796366
2019-06-14 02:28:56,754 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1453.44660401s left)
2019-06-14 02:29:19,044 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022919030117
2019-06-14 02:29:19,077 [salt.minion      :1432][INFO    ][11549] Starting a new job with PID 11549
2019-06-14 02:29:19,102 [salt.minion      :1711][INFO    ][11549] Returning information for job: 20190614022919030117
2019-06-14 02:29:29,746 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1420.45369506s left)
2019-06-14 02:29:49,081 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614022949068810
2019-06-14 02:29:49,110 [salt.minion      :1432][INFO    ][11584] Starting a new job with PID 11584
2019-06-14 02:29:49,132 [salt.minion      :1711][INFO    ][11584] Returning information for job: 20190614022949068810
2019-06-14 02:30:02,963 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1387.23650098s left)
2019-06-14 02:30:19,103 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023019090537
2019-06-14 02:30:19,129 [salt.minion      :1432][INFO    ][11623] Starting a new job with PID 11623
2019-06-14 02:30:19,152 [salt.minion      :1711][INFO    ][11623] Returning information for job: 20190614023019090537
2019-06-14 02:30:35,971 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1354.22850704s left)
2019-06-14 02:30:49,149 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023049135684
2019-06-14 02:30:49,176 [salt.minion      :1432][INFO    ][11657] Starting a new job with PID 11657
2019-06-14 02:30:49,199 [salt.minion      :1711][INFO    ][11657] Returning information for job: 20190614023049135684
2019-06-14 02:31:08,888 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1321.31151891s left)
2019-06-14 02:31:19,195 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023119186693
2019-06-14 02:31:19,217 [salt.minion      :1432][INFO    ][11703] Starting a new job with PID 11703
2019-06-14 02:31:19,237 [salt.minion      :1711][INFO    ][11703] Returning information for job: 20190614023119186693
2019-06-14 02:31:41,616 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.58376908s left)
2019-06-14 02:31:49,276 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023149254767
2019-06-14 02:31:49,302 [salt.minion      :1432][INFO    ][11742] Starting a new job with PID 11742
2019-06-14 02:31:49,325 [salt.minion      :1711][INFO    ][11742] Returning information for job: 20190614023149254767
2019-06-14 02:32:14,386 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1255.81371999s left)
2019-06-14 02:32:19,376 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023219364988
2019-06-14 02:32:19,403 [salt.minion      :1432][INFO    ][11789] Starting a new job with PID 11789
2019-06-14 02:32:19,427 [salt.minion      :1711][INFO    ][11789] Returning information for job: 20190614023219364988
2019-06-14 02:32:47,152 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.04768896s left)
2019-06-14 02:32:49,460 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023249445492
2019-06-14 02:32:49,491 [salt.minion      :1432][INFO    ][11827] Starting a new job with PID 11827
2019-06-14 02:32:49,514 [salt.minion      :1711][INFO    ][11827] Returning information for job: 20190614023249445492
2019-06-14 02:33:19,565 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023319552871
2019-06-14 02:33:19,590 [salt.minion      :1432][INFO    ][11867] Starting a new job with PID 11867
2019-06-14 02:33:19,613 [salt.minion      :1711][INFO    ][11867] Returning information for job: 20190614023319552871
2019-06-14 02:33:20,135 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.06476307s left)
2019-06-14 02:33:49,639 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023349628672
2019-06-14 02:33:49,667 [salt.minion      :1432][INFO    ][11899] Starting a new job with PID 11899
2019-06-14 02:33:49,693 [salt.minion      :1711][INFO    ][11899] Returning information for job: 20190614023349628672
2019-06-14 02:33:52,940 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.26007891s left)
2019-06-14 02:34:19,771 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023419754968
2019-06-14 02:34:19,795 [salt.minion      :1432][INFO    ][11944] Starting a new job with PID 11944
2019-06-14 02:34:19,823 [salt.minion      :1711][INFO    ][11944] Returning information for job: 20190614023419754968
2019-06-14 02:34:25,811 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.38915491s left)
2019-06-14 02:34:49,927 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023449912901
2019-06-14 02:34:49,956 [salt.minion      :1432][INFO    ][11981] Starting a new job with PID 11981
2019-06-14 02:34:49,983 [salt.minion      :1711][INFO    ][11981] Returning information for job: 20190614023449912901
2019-06-14 02:34:58,519 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.68087411s left)
2019-06-14 02:35:20,096 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023520083229
2019-06-14 02:35:20,123 [salt.minion      :1432][INFO    ][12023] Starting a new job with PID 12023
2019-06-14 02:35:20,145 [salt.minion      :1711][INFO    ][12023] Returning information for job: 20190614023520083229
2019-06-14 02:35:31,447 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1058.75276709s left)
2019-06-14 02:35:50,245 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023550229138
2019-06-14 02:35:50,272 [salt.minion      :1432][INFO    ][12059] Starting a new job with PID 12059
2019-06-14 02:35:50,295 [salt.minion      :1711][INFO    ][12059] Returning information for job: 20190614023550229138
2019-06-14 02:36:04,264 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1025.93566394s left)
2019-06-14 02:36:20,408 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023620393121
2019-06-14 02:36:20,432 [salt.minion      :1432][INFO    ][12100] Starting a new job with PID 12100
2019-06-14 02:36:20,457 [salt.minion      :1711][INFO    ][12100] Returning information for job: 20190614023620393121
2019-06-14 02:36:37,144 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.058593035s left)
2019-06-14 02:36:50,592 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023650576501
2019-06-14 02:36:50,624 [salt.minion      :1432][INFO    ][12136] Starting a new job with PID 12136
2019-06-14 02:36:50,647 [salt.minion      :1711][INFO    ][12136] Returning information for job: 20190614023650576501
2019-06-14 02:37:10,013 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.187331915s left)
2019-06-14 02:37:20,624 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023720611562
2019-06-14 02:37:20,653 [salt.minion      :1432][INFO    ][12202] Starting a new job with PID 12202
2019-06-14 02:37:20,688 [salt.minion      :1711][INFO    ][12202] Returning information for job: 20190614023720611562
2019-06-14 02:37:42,937 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.262828112s left)
2019-06-14 02:37:50,649 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023750635952
2019-06-14 02:37:50,675 [salt.minion      :1432][INFO    ][12243] Starting a new job with PID 12243
2019-06-14 02:37:50,699 [salt.minion      :1711][INFO    ][12243] Returning information for job: 20190614023750635952
2019-06-14 02:38:15,672 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (894.528006077s left)
2019-06-14 02:38:20,684 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023820672266
2019-06-14 02:38:20,713 [salt.minion      :1432][INFO    ][12299] Starting a new job with PID 12299
2019-06-14 02:38:20,747 [salt.minion      :1711][INFO    ][12299] Returning information for job: 20190614023820672266
2019-06-14 02:38:48,739 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (861.461410999s left)
2019-06-14 02:38:50,749 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023850736353
2019-06-14 02:38:50,777 [salt.minion      :1432][INFO    ][12332] Starting a new job with PID 12332
2019-06-14 02:38:50,802 [salt.minion      :1711][INFO    ][12332] Returning information for job: 20190614023850736353
2019-06-14 02:39:20,791 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023920779900
2019-06-14 02:39:20,815 [salt.minion      :1432][INFO    ][12379] Starting a new job with PID 12379
2019-06-14 02:39:20,843 [salt.minion      :1711][INFO    ][12379] Returning information for job: 20190614023920779900
2019-06-14 02:39:21,728 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (828.4714849s left)
2019-06-14 02:39:50,895 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614023950880533
2019-06-14 02:39:50,922 [salt.minion      :1432][INFO    ][12400] Starting a new job with PID 12400
2019-06-14 02:39:50,945 [salt.minion      :1711][INFO    ][12400] Returning information for job: 20190614023950880533
2019-06-14 02:39:54,475 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (795.724653959s left)
2019-06-14 02:40:20,972 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024020960063
2019-06-14 02:40:21,001 [salt.minion      :1432][INFO    ][12452] Starting a new job with PID 12452
2019-06-14 02:40:21,024 [salt.minion      :1711][INFO    ][12452] Returning information for job: 20190614024020960063
2019-06-14 02:40:27,286 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (762.914601088s left)
2019-06-14 02:40:51,109 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024051093605
2019-06-14 02:40:51,139 [salt.minion      :1432][INFO    ][12477] Starting a new job with PID 12477
2019-06-14 02:40:51,165 [salt.minion      :1711][INFO    ][12477] Returning information for job: 20190614024051093605
2019-06-14 02:41:00,236 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (729.964015007s left)
2019-06-14 02:41:21,207 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024121193663
2019-06-14 02:41:21,234 [salt.minion      :1432][INFO    ][12531] Starting a new job with PID 12531
2019-06-14 02:41:21,269 [salt.minion      :1711][INFO    ][12531] Returning information for job: 20190614024121193663
2019-06-14 02:41:33,081 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.118931055s left)
2019-06-14 02:41:51,378 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024151363279
2019-06-14 02:41:51,406 [salt.minion      :1432][INFO    ][12559] Starting a new job with PID 12559
2019-06-14 02:41:51,429 [salt.minion      :1711][INFO    ][12559] Returning information for job: 20190614024151363279
2019-06-14 02:42:05,821 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.379112005s left)
2019-06-14 02:42:21,509 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024221497291
2019-06-14 02:42:21,536 [salt.minion      :1432][INFO    ][12612] Starting a new job with PID 12612
2019-06-14 02:42:21,558 [salt.minion      :1711][INFO    ][12612] Returning information for job: 20190614024221497291
2019-06-14 02:42:38,562 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (631.638287067s left)
2019-06-14 02:42:51,704 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024251694236
2019-06-14 02:42:51,731 [salt.minion      :1432][INFO    ][12639] Starting a new job with PID 12639
2019-06-14 02:42:51,751 [salt.minion      :1711][INFO    ][12639] Returning information for job: 20190614024251694236
2019-06-14 02:43:11,408 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (598.791863918s left)
2019-06-14 02:43:21,893 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024321880015
2019-06-14 02:43:21,909 [salt.minion      :1432][INFO    ][12690] Starting a new job with PID 12690
2019-06-14 02:43:21,932 [salt.minion      :1711][INFO    ][12690] Returning information for job: 20190614024321880015
2019-06-14 02:43:44,423 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (565.776844978s left)
2019-06-14 02:43:52,115 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024352100768
2019-06-14 02:43:52,144 [salt.minion      :1432][INFO    ][12714] Starting a new job with PID 12714
2019-06-14 02:43:52,167 [salt.minion      :1711][INFO    ][12714] Returning information for job: 20190614024352100768
2019-06-14 02:44:17,164 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (533.035834074s left)
2019-06-14 02:44:22,175 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024422160222
2019-06-14 02:44:22,206 [salt.minion      :1432][INFO    ][12768] Starting a new job with PID 12768
2019-06-14 02:44:22,230 [salt.minion      :1711][INFO    ][12768] Returning information for job: 20190614024422160222
2019-06-14 02:44:49,980 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (500.219592094s left)
2019-06-14 02:44:52,221 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024452204686
2019-06-14 02:44:52,252 [salt.minion      :1432][INFO    ][12788] Starting a new job with PID 12788
2019-06-14 02:44:52,276 [salt.minion      :1711][INFO    ][12788] Returning information for job: 20190614024452204686
2019-06-14 02:45:22,320 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024522308969
2019-06-14 02:45:22,341 [salt.minion      :1432][INFO    ][12843] Starting a new job with PID 12843
2019-06-14 02:45:22,366 [salt.minion      :1711][INFO    ][12843] Returning information for job: 20190614024522308969
2019-06-14 02:45:22,772 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (467.428102016s left)
2019-06-14 02:45:52,390 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024552375663
2019-06-14 02:45:52,420 [salt.minion      :1432][INFO    ][12862] Starting a new job with PID 12862
2019-06-14 02:45:52,444 [salt.minion      :1711][INFO    ][12862] Returning information for job: 20190614024552375663
2019-06-14 02:45:55,503 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (434.69639492s left)
2019-06-14 02:46:22,495 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024622486775
2019-06-14 02:46:22,524 [salt.minion      :1432][INFO    ][12915] Starting a new job with PID 12915
2019-06-14 02:46:22,545 [salt.minion      :1711][INFO    ][12915] Returning information for job: 20190614024622486775
2019-06-14 02:46:28,431 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (401.770548105s left)
2019-06-14 02:46:52,665 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024652651338
2019-06-14 02:46:52,695 [salt.minion      :1432][INFO    ][12939] Starting a new job with PID 12939
2019-06-14 02:46:52,720 [salt.minion      :1711][INFO    ][12939] Returning information for job: 20190614024652651338
2019-06-14 02:47:01,438 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (368.762335062s left)
2019-06-14 02:47:22,880 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024722865536
2019-06-14 02:47:22,907 [salt.minion      :1432][INFO    ][13137] Starting a new job with PID 13137
2019-06-14 02:47:22,936 [salt.minion      :1711][INFO    ][13137] Returning information for job: 20190614024722865536
2019-06-14 02:47:34,187 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (336.012802124s left)
2019-06-14 02:47:53,084 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024753070914
2019-06-14 02:47:53,111 [salt.minion      :1432][INFO    ][13162] Starting a new job with PID 13162
2019-06-14 02:47:53,134 [salt.minion      :1711][INFO    ][13162] Returning information for job: 20190614024753070914
2019-06-14 02:48:07,099 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (303.101243973s left)
2019-06-14 02:48:23,323 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024823305636
2019-06-14 02:48:23,350 [salt.minion      :1432][INFO    ][13220] Starting a new job with PID 13220
2019-06-14 02:48:23,375 [salt.minion      :1711][INFO    ][13220] Returning information for job: 20190614024823305636
2019-06-14 02:48:39,874 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (270.325664043s left)
2019-06-14 02:48:53,536 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024853519243
2019-06-14 02:48:53,563 [salt.minion      :1432][INFO    ][13242] Starting a new job with PID 13242
2019-06-14 02:48:53,586 [salt.minion      :1711][INFO    ][13242] Returning information for job: 20190614024853519243
2019-06-14 02:49:12,689 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (237.511254072s left)
2019-06-14 02:49:23,575 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024923556015
2019-06-14 02:49:23,601 [salt.minion      :1432][INFO    ][13300] Starting a new job with PID 13300
2019-06-14 02:49:23,625 [salt.minion      :1711][INFO    ][13300] Returning information for job: 20190614024923556015
2019-06-14 02:49:45,401 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (204.798804998s left)
2019-06-14 02:49:53,665 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614024953652643
2019-06-14 02:49:53,682 [salt.minion      :1432][INFO    ][13321] Starting a new job with PID 13321
2019-06-14 02:49:53,707 [salt.minion      :1711][INFO    ][13321] Returning information for job: 20190614024953652643
2019-06-14 02:50:18,308 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (171.891386986s left)
2019-06-14 02:50:23,737 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025023728889
2019-06-14 02:50:23,764 [salt.minion      :1432][INFO    ][13381] Starting a new job with PID 13381
2019-06-14 02:50:23,792 [salt.minion      :1711][INFO    ][13381] Returning information for job: 20190614025023728889
2019-06-14 02:50:51,095 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (139.104680061s left)
2019-06-14 02:50:53,860 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025053844990
2019-06-14 02:50:53,887 [salt.minion      :1432][INFO    ][13401] Starting a new job with PID 13401
2019-06-14 02:50:53,912 [salt.minion      :1711][INFO    ][13401] Returning information for job: 20190614025053844990
2019-06-14 02:51:23,959 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025123947964
2019-06-14 02:51:23,983 [salt.minion      :1432][INFO    ][13460] Starting a new job with PID 13460
2019-06-14 02:51:24,008 [salt.minion      :1711][INFO    ][13460] Returning information for job: 20190614025123947964
2019-06-14 02:51:24,117 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (106.082772017s left)
2019-06-14 02:51:54,118 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025154102815
2019-06-14 02:51:54,149 [salt.minion      :1432][INFO    ][13478] Starting a new job with PID 13478
2019-06-14 02:51:54,176 [salt.minion      :1711][INFO    ][13478] Returning information for job: 20190614025154102815
2019-06-14 02:51:56,976 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (73.2242720127s left)
2019-06-14 02:52:24,289 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025224272424
2019-06-14 02:52:24,316 [salt.minion      :1432][INFO    ][13535] Starting a new job with PID 13535
2019-06-14 02:52:24,339 [salt.minion      :1711][INFO    ][13535] Returning information for job: 20190614025224272424
2019-06-14 02:52:29,767 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (40.4329020977s left)
2019-06-14 02:52:54,467 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025254452510
2019-06-14 02:52:54,498 [salt.minion      :1432][INFO    ][13558] Starting a new job with PID 13558
2019-06-14 02:52:54,524 [salt.minion      :1711][INFO    ][13558] Returning information for job: 20190614025254452510
2019-06-14 02:53:02,637 [salt.loaded.ext.module.maas:1023][INFO    ][7937] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (7.56300210953s left)
2019-06-14 02:53:24,680 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025324670205
2019-06-14 02:53:24,712 [salt.minion      :1432][INFO    ][13613] Starting a new job with PID 13613
2019-06-14 02:53:24,735 [salt.minion      :1711][INFO    ][13613] Returning information for job: 20190614025324670205
2019-06-14 02:53:35,523 [salt.state       :302 ][ERROR   ][7937] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-14 02:53:35,524 [salt.state       :1951][INFO    ][7937] Completed state [maas.wait_for_machine_status] at time 02:53:35.523991 duration_in_ms=2275332.459
2019-06-14 02:53:35,530 [salt.minion      :1711][INFO    ][7937] Returning information for job: 20190614021530054541
2019-06-14 02:53:46,359 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command pillar.get with jid 20190614025346346706
2019-06-14 02:53:46,388 [salt.minion      :1432][INFO    ][13630] Starting a new job with PID 13630
2019-06-14 02:53:46,398 [salt.minion      :1711][INFO    ][13630] Returning information for job: 20190614025346346706
2019-06-14 02:53:46,969 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command service.status with jid 20190614025346957029
2019-06-14 02:53:46,996 [salt.minion      :1432][INFO    ][13637] Starting a new job with PID 13637
2019-06-14 02:53:47,574 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13637] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-14 02:53:47,621 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13637] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-14 02:53:47,646 [salt.minion      :1711][INFO    ][13637] Returning information for job: 20190614025346957029
2019-06-14 02:53:48,217 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614025348206670
2019-06-14 02:53:48,248 [salt.minion      :1432][INFO    ][13648] Starting a new job with PID 13648
2019-06-14 02:53:56,249 [salt.state       :915 ][INFO    ][13648] Loading fresh modules for state activity
2019-06-14 02:53:56,857 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command 'salt-minion --version' in directory '/root'
2019-06-14 02:53:57,163 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command 'salt-minion --version' in directory '/root'
2019-06-14 02:53:58,182 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command 'salt-minion --version' in directory '/root'
2019-06-14 02:53:58,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command 'salt-minion --version' in directory '/root'
2019-06-14 02:54:00,314 [salt.state       :1780][INFO    ][13648] Running state [salt-minion] at time 02:54:00.314509
2019-06-14 02:54:00,314 [salt.state       :1813][INFO    ][13648] Executing state pkg.installed for [salt-minion]
2019-06-14 02:54:00,315 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-14 02:54:00,425 [salt.state       :300 ][INFO    ][13648] All specified packages are already installed
2019-06-14 02:54:00,426 [salt.state       :1951][INFO    ][13648] Completed state [salt-minion] at time 02:54:00.425735 duration_in_ms=111.227
2019-06-14 02:54:00,426 [salt.state       :1780][INFO    ][13648] Running state [salt_minion_dependency_packages] at time 02:54:00.426375
2019-06-14 02:54:00,426 [salt.state       :1813][INFO    ][13648] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-14 02:54:00,436 [salt.state       :300 ][INFO    ][13648] All specified packages are already installed
2019-06-14 02:54:00,436 [salt.state       :1951][INFO    ][13648] Completed state [salt_minion_dependency_packages] at time 02:54:00.436550 duration_in_ms=10.175
2019-06-14 02:54:00,439 [salt.state       :1780][INFO    ][13648] Running state [/etc/salt/minion.d/minion.conf] at time 02:54:00.439816
2019-06-14 02:54:00,440 [salt.state       :1813][INFO    ][13648] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-14 02:54:00,730 [salt.state       :300 ][INFO    ][13648] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-14 02:54:00,730 [salt.state       :1951][INFO    ][13648] Completed state [/etc/salt/minion.d/minion.conf] at time 02:54:00.730896 duration_in_ms=291.08
2019-06-14 02:54:00,731 [salt.state       :1780][INFO    ][13648] Running state [python-netaddr] at time 02:54:00.731166
2019-06-14 02:54:00,731 [salt.state       :1813][INFO    ][13648] Executing state pkg.installed for [python-netaddr]
2019-06-14 02:54:00,748 [salt.state       :300 ][INFO    ][13648] All specified packages are already installed
2019-06-14 02:54:00,749 [salt.state       :1951][INFO    ][13648] Completed state [python-netaddr] at time 02:54:00.749258 duration_in_ms=18.091
2019-06-14 02:54:00,754 [salt.state       :1780][INFO    ][13648] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:54:00.754119
2019-06-14 02:54:00,754 [salt.state       :1813][INFO    ][13648] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-14 02:54:00,768 [salt.state       :300 ][INFO    ][13648] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-14 02:54:00,768 [salt.state       :1951][INFO    ][13648] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:54:00.768539 duration_in_ms=14.419
2019-06-14 02:54:00,771 [salt.state       :1780][INFO    ][13648] Running state [salt-minion] at time 02:54:00.771711
2019-06-14 02:54:00,771 [salt.state       :1813][INFO    ][13648] Executing state service.running for [salt-minion]
2019-06-14 02:54:00,772 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-14 02:54:00,815 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-14 02:54:00,839 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-14 02:54:00,863 [salt.state       :300 ][INFO    ][13648] The service salt-minion is already running
2019-06-14 02:54:00,864 [salt.state       :1951][INFO    ][13648] Completed state [salt-minion] at time 02:54:00.864033 duration_in_ms=92.322
2019-06-14 02:54:00,868 [salt.state       :1780][INFO    ][13648] Running state [/etc/salt/grains.d] at time 02:54:00.868452
2019-06-14 02:54:00,868 [salt.state       :1813][INFO    ][13648] Executing state file.directory for [/etc/salt/grains.d]
2019-06-14 02:54:00,870 [salt.state       :300 ][INFO    ][13648] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-14 02:54:00,870 [salt.state       :1951][INFO    ][13648] Completed state [/etc/salt/grains.d] at time 02:54:00.870593 duration_in_ms=2.14
2019-06-14 02:54:00,871 [salt.state       :1780][INFO    ][13648] Running state [/etc/salt/grains] at time 02:54:00.871625
2019-06-14 02:54:00,872 [salt.state       :1813][INFO    ][13648] Executing state file.managed for [/etc/salt/grains]
2019-06-14 02:54:00,872 [salt.state       :300 ][INFO    ][13648] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-14 02:54:00,873 [salt.state       :1951][INFO    ][13648] Completed state [/etc/salt/grains] at time 02:54:00.873112 duration_in_ms=1.486
2019-06-14 02:54:00,873 [salt.state       :1780][INFO    ][13648] Running state [/etc/salt/grains.d/placeholder] at time 02:54:00.873852
2019-06-14 02:54:00,874 [salt.state       :1813][INFO    ][13648] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-14 02:54:00,875 [salt.state       :300 ][INFO    ][13648] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-14 02:54:00,875 [salt.state       :1951][INFO    ][13648] Completed state [/etc/salt/grains.d/placeholder] at time 02:54:00.875317 duration_in_ms=1.464
2019-06-14 02:54:00,876 [salt.state       :1780][INFO    ][13648] Running state [/etc/salt/grains.d/sphinx] at time 02:54:00.876037
2019-06-14 02:54:00,876 [salt.state       :1813][INFO    ][13648] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-14 02:54:00,879 [salt.state       :300 ][INFO    ][13648] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-14 02:54:00,879 [salt.state       :1951][INFO    ][13648] Completed state [/etc/salt/grains.d/sphinx] at time 02:54:00.879631 duration_in_ms=3.594
2019-06-14 02:54:00,881 [salt.state       :1780][INFO    ][13648] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:54:00.881697
2019-06-14 02:54:00,881 [salt.state       :1813][INFO    ][13648] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-14 02:54:00,882 [salt.state       :300 ][INFO    ][13648] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-14 02:54:00,882 [salt.state       :1951][INFO    ][13648] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:54:00.882468 duration_in_ms=0.771
2019-06-14 02:54:00,882 [salt.state       :1780][INFO    ][13648] Running state [/etc/salt/grains.d/dns_records] at time 02:54:00.882946
2019-06-14 02:54:00,883 [salt.state       :1813][INFO    ][13648] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-14 02:54:00,884 [salt.state       :300 ][INFO    ][13648] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-14 02:54:00,884 [salt.state       :1951][INFO    ][13648] Completed state [/etc/salt/grains.d/dns_records] at time 02:54:00.884266 duration_in_ms=1.32
2019-06-14 02:54:00,885 [salt.state       :1780][INFO    ][13648] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:54:00.885180
2019-06-14 02:54:00,885 [salt.state       :1813][INFO    ][13648] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-14 02:54:00,885 [salt.state       :300 ][INFO    ][13648] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-14 02:54:00,885 [salt.state       :1951][INFO    ][13648] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:54:00.885948 duration_in_ms=0.768
2019-06-14 02:54:00,886 [salt.state       :1780][INFO    ][13648] Running state [/etc/salt/grains.d/salt] at time 02:54:00.886424
2019-06-14 02:54:00,886 [salt.state       :1813][INFO    ][13648] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-14 02:54:00,887 [salt.state       :300 ][INFO    ][13648] File /etc/salt/grains.d/salt is in the correct state
2019-06-14 02:54:00,887 [salt.state       :1951][INFO    ][13648] Completed state [/etc/salt/grains.d/salt] at time 02:54:00.887744 duration_in_ms=1.32
2019-06-14 02:54:00,888 [salt.state       :1780][INFO    ][13648] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:54:00.888614
2019-06-14 02:54:00,888 [salt.state       :1813][INFO    ][13648] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-14 02:54:00,889 [salt.state       :300 ][INFO    ][13648] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-14 02:54:00,889 [salt.state       :1951][INFO    ][13648] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:54:00.889361 duration_in_ms=0.747
2019-06-14 02:54:00,892 [salt.state       :1780][INFO    ][13648] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:54:00.892173
2019-06-14 02:54:00,892 [salt.state       :1813][INFO    ][13648] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-14 02:54:00,892 [salt.state       :300 ][INFO    ][13648] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-14 02:54:00,893 [salt.state       :1951][INFO    ][13648] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:54:00.892956 duration_in_ms=0.783
2019-06-14 02:54:00,893 [salt.state       :1780][INFO    ][13648] Running state [mine.update] at time 02:54:00.893613
2019-06-14 02:54:00,893 [salt.state       :1813][INFO    ][13648] Executing state module.wait for [mine.update]
2019-06-14 02:54:00,894 [salt.state       :300 ][INFO    ][13648] No changes made for mine.update
2019-06-14 02:54:00,894 [salt.state       :1951][INFO    ][13648] Completed state [mine.update] at time 02:54:00.894311 duration_in_ms=0.697
2019-06-14 02:54:00,894 [salt.state       :1780][INFO    ][13648] Running state [ca-certificates] at time 02:54:00.894537
2019-06-14 02:54:00,894 [salt.state       :1813][INFO    ][13648] Executing state pkg.installed for [ca-certificates]
2019-06-14 02:54:00,904 [salt.state       :300 ][INFO    ][13648] All specified packages are already installed
2019-06-14 02:54:00,904 [salt.state       :1951][INFO    ][13648] Completed state [ca-certificates] at time 02:54:00.904449 duration_in_ms=9.912
2019-06-14 02:54:00,905 [salt.state       :1780][INFO    ][13648] Running state [update-ca-certificates] at time 02:54:00.905129
2019-06-14 02:54:00,905 [salt.state       :1813][INFO    ][13648] Executing state cmd.wait for [update-ca-certificates]
2019-06-14 02:54:00,905 [salt.state       :300 ][INFO    ][13648] No changes made for update-ca-certificates
2019-06-14 02:54:00,905 [salt.state       :1951][INFO    ][13648] Completed state [update-ca-certificates] at time 02:54:00.905869 duration_in_ms=0.741
2019-06-14 02:54:00,906 [salt.state       :1780][INFO    ][13648] Running state [iptables] at time 02:54:00.906102
2019-06-14 02:54:00,906 [salt.state       :1813][INFO    ][13648] Executing state pkg.installed for [iptables]
2019-06-14 02:54:00,916 [salt.state       :300 ][INFO    ][13648] All specified packages are already installed
2019-06-14 02:54:00,916 [salt.state       :1951][INFO    ][13648] Completed state [iptables] at time 02:54:00.916540 duration_in_ms=10.438
2019-06-14 02:54:00,916 [salt.state       :1780][INFO    ][13648] Running state [iptables-persistent] at time 02:54:00.916781
2019-06-14 02:54:00,917 [salt.state       :1813][INFO    ][13648] Executing state pkg.installed for [iptables-persistent]
2019-06-14 02:54:00,925 [salt.state       :300 ][INFO    ][13648] All specified packages are already installed
2019-06-14 02:54:00,926 [salt.state       :1951][INFO    ][13648] Completed state [iptables-persistent] at time 02:54:00.926453 duration_in_ms=9.672
2019-06-14 02:54:00,927 [salt.state       :1780][INFO    ][13648] Running state [iptables_modules_v4_load] at time 02:54:00.927701
2019-06-14 02:54:00,928 [salt.state       :1813][INFO    ][13648] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-14 02:54:00,928 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command 'lsmod' in directory '/root'
2019-06-14 02:54:00,955 [salt.state       :300 ][INFO    ][13648] Kernel modules iptable_filter, ip_tables are already present
2019-06-14 02:54:00,955 [salt.state       :1951][INFO    ][13648] Completed state [iptables_modules_v4_load] at time 02:54:00.955866 duration_in_ms=28.164
2019-06-14 02:54:00,957 [salt.state       :1780][INFO    ][13648] Running state [/etc/iptables/rules.v4] at time 02:54:00.957255
2019-06-14 02:54:00,958 [salt.state       :1813][INFO    ][13648] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-14 02:54:01,066 [salt.state       :300 ][INFO    ][13648] File /etc/iptables/rules.v4 is in the correct state
2019-06-14 02:54:01,066 [salt.state       :1951][INFO    ][13648] Completed state [/etc/iptables/rules.v4] at time 02:54:01.066710 duration_in_ms=109.455
2019-06-14 02:54:01,068 [salt.state       :1780][INFO    ][13648] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:54:01.068696
2019-06-14 02:54:01,069 [salt.state       :1813][INFO    ][13648] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-14 02:54:01,070 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-14 02:54:01,092 [salt.state       :300 ][INFO    ][13648] onlyif execution failed
2019-06-14 02:54:01,093 [salt.state       :1951][INFO    ][13648] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:54:01.093265 duration_in_ms=24.569
2019-06-14 02:54:01,096 [salt.state       :1780][INFO    ][13648] Running state [netfilter-persistent] at time 02:54:01.096649
2019-06-14 02:54:01,097 [salt.state       :1813][INFO    ][13648] Executing state service.running for [netfilter-persistent]
2019-06-14 02:54:01,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-14 02:54:01,127 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-14 02:54:01,152 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-14 02:54:01,175 [salt.state       :300 ][INFO    ][13648] The service netfilter-persistent is already running
2019-06-14 02:54:01,175 [salt.state       :1951][INFO    ][13648] Completed state [netfilter-persistent] at time 02:54:01.175585 duration_in_ms=78.936
2019-06-14 02:54:01,177 [salt.state       :1780][INFO    ][13648] Running state [iptables_extra.remove_stale_tables] at time 02:54:01.177069
2019-06-14 02:54:01,177 [salt.state       :1813][INFO    ][13648] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-14 02:54:01,180 [salt.state       :300 ][INFO    ][13648] No changes made for iptables_extra.remove_stale_tables
2019-06-14 02:54:01,181 [salt.state       :1951][INFO    ][13648] Completed state [iptables_extra.remove_stale_tables] at time 02:54:01.181005 duration_in_ms=3.936
2019-06-14 02:54:01,181 [salt.state       :1780][INFO    ][13648] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:54:01.181343
2019-06-14 02:54:01,182 [salt.state       :1813][INFO    ][13648] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-14 02:54:01,182 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13648] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-14 02:54:01,198 [salt.state       :300 ][INFO    ][13648] onlyif execution failed
2019-06-14 02:54:01,199 [salt.state       :1951][INFO    ][13648] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:54:01.199302 duration_in_ms=17.96
2019-06-14 02:54:01,200 [salt.state       :1780][INFO    ][13648] Running state [/etc/iptables/rules.v6] at time 02:54:01.200439
2019-06-14 02:54:01,200 [salt.state       :1813][INFO    ][13648] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-14 02:54:01,201 [salt.state       :300 ][INFO    ][13648] File /etc/iptables/rules.v6 is not present
2019-06-14 02:54:01,201 [salt.state       :1951][INFO    ][13648] Completed state [/etc/iptables/rules.v6] at time 02:54:01.201567 duration_in_ms=1.128
2019-06-14 02:54:01,205 [salt.state       :1780][INFO    ][13648] Running state [iptables_extra.flush_all] at time 02:54:01.205460
2019-06-14 02:54:01,206 [salt.state       :1813][INFO    ][13648] Executing state module.wait for [iptables_extra.flush_all]
2019-06-14 02:54:01,206 [salt.state       :300 ][INFO    ][13648] No changes made for iptables_extra.flush_all
2019-06-14 02:54:01,207 [salt.state       :1951][INFO    ][13648] Completed state [iptables_extra.flush_all] at time 02:54:01.207197 duration_in_ms=1.737
2019-06-14 02:54:01,213 [salt.minion      :1711][INFO    ][13648] Returning information for job: 20190614025348206670
2019-06-14 02:54:01,833 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614025401820436
2019-06-14 02:54:01,858 [salt.minion      :1432][INFO    ][13739] Starting a new job with PID 13739
2019-06-14 02:54:03,019 [salt.state       :915 ][INFO    ][13739] Loading fresh modules for state activity
2019-06-14 02:54:04,001 [salt.state       :1780][INFO    ][13739] Running state [maas-rack-controller] at time 02:54:04.001479
2019-06-14 02:54:04,001 [salt.state       :1813][INFO    ][13739] Executing state pkg.installed for [maas-rack-controller]
2019-06-14 02:54:04,003 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13739] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-14 02:54:04,121 [salt.state       :300 ][INFO    ][13739] All specified packages are already installed
2019-06-14 02:54:04,122 [salt.state       :1951][INFO    ][13739] Completed state [maas-rack-controller] at time 02:54:04.122032 duration_in_ms=120.553
2019-06-14 02:54:04,122 [salt.state       :1780][INFO    ][13739] Running state [ipmitool] at time 02:54:04.122346
2019-06-14 02:54:04,122 [salt.state       :1813][INFO    ][13739] Executing state pkg.installed for [ipmitool]
2019-06-14 02:54:04,132 [salt.state       :300 ][INFO    ][13739] All specified packages are already installed
2019-06-14 02:54:04,132 [salt.state       :1951][INFO    ][13739] Completed state [ipmitool] at time 02:54:04.132890 duration_in_ms=10.544
2019-06-14 02:54:04,136 [salt.state       :1780][INFO    ][13739] Running state [/etc/maas/rackd.conf] at time 02:54:04.136081
2019-06-14 02:54:04,136 [salt.state       :1813][INFO    ][13739] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-14 02:54:04,137 [salt.state       :300 ][INFO    ][13739] No changes needed to be made
2019-06-14 02:54:04,137 [salt.state       :1951][INFO    ][13739] Completed state [/etc/maas/rackd.conf] at time 02:54:04.137397 duration_in_ms=1.316
2019-06-14 02:54:04,137 [salt.state       :1780][INFO    ][13739] Running state [/etc/maas/rackd.conf] at time 02:54:04.137633
2019-06-14 02:54:04,137 [salt.state       :1813][INFO    ][13739] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-14 02:54:04,138 [salt.loaded.int.states.file:2298][WARNING ][13739] 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-06-14 02:54:04,138 [salt.state       :300 ][INFO    ][13739] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-14 02:54:04,139 [salt.state       :1951][INFO    ][13739] Completed state [/etc/maas/rackd.conf] at time 02:54:04.139060 duration_in_ms=1.427
2019-06-14 02:54:04,140 [salt.state       :1780][INFO    ][13739] Running state [maas-rackd] at time 02:54:04.140033
2019-06-14 02:54:04,140 [salt.state       :1813][INFO    ][13739] Executing state service.running for [maas-rackd]
2019-06-14 02:54:04,140 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13739] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-14 02:54:04,183 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13739] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-14 02:54:04,204 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13739] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-14 02:54:04,230 [salt.state       :300 ][INFO    ][13739] The service maas-rackd is already running
2019-06-14 02:54:04,231 [salt.state       :1951][INFO    ][13739] Completed state [maas-rackd] at time 02:54:04.231010 duration_in_ms=90.977
2019-06-14 02:54:04,233 [salt.minion      :1711][INFO    ][13739] Returning information for job: 20190614025401820436
2019-06-14 02:54:04,783 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614025404771130
2019-06-14 02:54:04,812 [salt.minion      :1432][INFO    ][13762] Starting a new job with PID 13762
2019-06-14 02:54:05,950 [salt.state       :915 ][INFO    ][13762] Loading fresh modules for state activity
2019-06-14 02:54:07,055 [salt.state       :1780][INFO    ][13762] Running state [maas-region-controller] at time 02:54:07.055558
2019-06-14 02:54:07,056 [salt.state       :1813][INFO    ][13762] Executing state pkg.installed for [maas-region-controller]
2019-06-14 02:54:07,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-14 02:54:07,169 [salt.state       :300 ][INFO    ][13762] All specified packages are already installed
2019-06-14 02:54:07,170 [salt.state       :1951][INFO    ][13762] Completed state [maas-region-controller] at time 02:54:07.170177 duration_in_ms=114.619
2019-06-14 02:54:07,170 [salt.state       :1780][INFO    ][13762] Running state [python-oauth] at time 02:54:07.170499
2019-06-14 02:54:07,170 [salt.state       :1813][INFO    ][13762] Executing state pkg.installed for [python-oauth]
2019-06-14 02:54:07,181 [salt.state       :300 ][INFO    ][13762] All specified packages are already installed
2019-06-14 02:54:07,181 [salt.state       :1951][INFO    ][13762] Completed state [python-oauth] at time 02:54:07.181351 duration_in_ms=10.852
2019-06-14 02:54:07,184 [salt.state       :1780][INFO    ][13762] Running state [/etc/maas/regiond.conf] at time 02:54:07.184498
2019-06-14 02:54:07,184 [salt.state       :1813][INFO    ][13762] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-14 02:54:07,189 [salt.state       :300 ][INFO    ][13762] No changes needed to be made
2019-06-14 02:54:07,189 [salt.state       :1951][INFO    ][13762] Completed state [/etc/maas/regiond.conf] at time 02:54:07.189587 duration_in_ms=5.089
2019-06-14 02:54:07,191 [salt.state       :1780][INFO    ][13762] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:54:07.191006
2019-06-14 02:54:07,191 [salt.state       :1813][INFO    ][13762] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-14 02:54:07,260 [salt.state       :300 ][INFO    ][13762] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-14 02:54:07,260 [salt.state       :1951][INFO    ][13762] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:54:07.260828 duration_in_ms=69.821
2019-06-14 02:54:07,261 [salt.state       :1780][INFO    ][13762] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:07.261368
2019-06-14 02:54:07,261 [salt.state       :1813][INFO    ][13762] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-14 02:54:07,266 [salt.state       :300 ][INFO    ][13762] No changes needed to be made
2019-06-14 02:54:07,266 [salt.state       :1951][INFO    ][13762] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:07.266373 duration_in_ms=5.005
2019-06-14 02:54:07,266 [salt.state       :1780][INFO    ][13762] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:07.266845
2019-06-14 02:54:07,267 [salt.state       :1813][INFO    ][13762] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-14 02:54:07,270 [salt.state       :300 ][INFO    ][13762] No changes needed to be made
2019-06-14 02:54:07,270 [salt.state       :1951][INFO    ][13762] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:54:07.270671 duration_in_ms=3.826
2019-06-14 02:54:07,271 [salt.state       :1780][INFO    ][13762] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:54:07.271148
2019-06-14 02:54:07,271 [salt.state       :1813][INFO    ][13762] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-14 02:54:07,302 [salt.state       :300 ][INFO    ][13762] No changes needed to be made
2019-06-14 02:54:07,302 [salt.state       :1951][INFO    ][13762] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:54:07.302594 duration_in_ms=31.446
2019-06-14 02:54:07,303 [salt.state       :1780][INFO    ][13762] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:54:07.303094
2019-06-14 02:54:07,303 [salt.state       :1813][INFO    ][13762] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-14 02:54:07,325 [salt.state       :300 ][INFO    ][13762] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-14 02:54:07,325 [salt.state       :1951][INFO    ][13762] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:54:07.325658 duration_in_ms=22.563
2019-06-14 02:54:07,328 [salt.state       :1780][INFO    ][13762] Running state [a2enmod headers] at time 02:54:07.328022
2019-06-14 02:54:07,328 [salt.state       :1813][INFO    ][13762] Executing state cmd.run for [a2enmod headers]
2019-06-14 02:54:07,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command 'a2enmod headers' in directory '/root'
2019-06-14 02:54:07,399 [salt.state       :300 ][INFO    ][13762] {'pid': 13782, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-14 02:54:07,399 [salt.state       :1951][INFO    ][13762] Completed state [a2enmod headers] at time 02:54:07.399626 duration_in_ms=71.604
2019-06-14 02:54:07,400 [salt.state       :1780][INFO    ][13762] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:54:07.400270
2019-06-14 02:54:07,400 [salt.state       :1813][INFO    ][13762] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-14 02:54:07,427 [salt.state       :300 ][INFO    ][13762] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-14 02:54:07,428 [salt.state       :1951][INFO    ][13762] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:54:07.428384 duration_in_ms=28.114
2019-06-14 02:54:07,429 [salt.state       :1780][INFO    ][13762] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:54:07.429412
2019-06-14 02:54:07,432 [salt.state       :1813][INFO    ][13762] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-14 02:54:07,493 [salt.state       :300 ][INFO    ][13762] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-14 02:54:07,493 [salt.state       :1951][INFO    ][13762] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:54:07.493783 duration_in_ms=64.371
2019-06-14 02:54:07,494 [salt.state       :1780][INFO    ][13762] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:54:07.494796
2019-06-14 02:54:07,495 [salt.state       :1813][INFO    ][13762] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-14 02:54:07,562 [salt.state       :300 ][INFO    ][13762] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-14 02:54:07,563 [salt.state       :1951][INFO    ][13762] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:54:07.563030 duration_in_ms=68.235
2019-06-14 02:54:07,563 [salt.state       :1780][INFO    ][13762] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:54:07.563517
2019-06-14 02:54:07,563 [salt.state       :1813][INFO    ][13762] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-14 02:54:07,640 [salt.state       :300 ][INFO    ][13762] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-14 02:54:07,640 [salt.state       :1951][INFO    ][13762] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:54:07.640626 duration_in_ms=77.109
2019-06-14 02:54:07,640 [salt.state       :1780][INFO    ][13762] Running state [/root/.pgpass] at time 02:54:07.640902
2019-06-14 02:54:07,641 [salt.state       :1813][INFO    ][13762] Executing state file.managed for [/root/.pgpass]
2019-06-14 02:54:07,690 [salt.state       :300 ][INFO    ][13762] File /root/.pgpass is in the correct state
2019-06-14 02:54:07,690 [salt.state       :1951][INFO    ][13762] Completed state [/root/.pgpass] at time 02:54:07.690830 duration_in_ms=49.927
2019-06-14 02:54:07,696 [salt.state       :1780][INFO    ][13762] Running state [maas-region syncdb --noinput] at time 02:54:07.696181
2019-06-14 02:54:07,696 [salt.state       :1813][INFO    ][13762] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-14 02:54:07,697 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-14 02:54:10,304 [salt.state       :300 ][INFO    ][13762] {'pid': 13795, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, auth, metadataserver, maasserver, sessions, sites, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-14 02:54:10,305 [salt.state       :1951][INFO    ][13762] Completed state [maas-region syncdb --noinput] at time 02:54:10.305561 duration_in_ms=2609.379
2019-06-14 02:54:10,307 [salt.state       :2022][WARNING ][13762] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-14 02:54:10,311 [salt.state       :1780][INFO    ][13762] Running state [maas-regiond] at time 02:54:10.311209
2019-06-14 02:54:10,311 [salt.state       :1813][INFO    ][13762] Executing state service.running for [maas-regiond]
2019-06-14 02:54:10,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-14 02:54:10,366 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-14 02:54:10,390 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-14 02:54:10,414 [salt.state       :300 ][INFO    ][13762] The service maas-regiond is already running
2019-06-14 02:54:10,415 [salt.state       :1951][INFO    ][13762] Completed state [maas-regiond] at time 02:54:10.415038 duration_in_ms=103.828
2019-06-14 02:54:10,420 [salt.state       :1780][INFO    ][13762] Running state [bind9] at time 02:54:10.420412
2019-06-14 02:54:10,421 [salt.state       :1813][INFO    ][13762] Executing state service.running for [bind9]
2019-06-14 02:54:10,422 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-14 02:54:10,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-14 02:54:10,472 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-14 02:54:10,495 [salt.state       :300 ][INFO    ][13762] The service bind9 is already running
2019-06-14 02:54:10,496 [salt.state       :1951][INFO    ][13762] Completed state [bind9] at time 02:54:10.495960 duration_in_ms=75.548
2019-06-14 02:54:10,499 [salt.state       :1780][INFO    ][13762] Running state [apache2] at time 02:54:10.499086
2019-06-14 02:54:10,499 [salt.state       :1813][INFO    ][13762] Executing state service.running for [apache2]
2019-06-14 02:54:10,500 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-14 02:54:10,524 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-14 02:54:10,548 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-14 02:54:10,580 [salt.state       :300 ][INFO    ][13762] The service apache2 is already running
2019-06-14 02:54:10,581 [salt.state       :1951][INFO    ][13762] Completed state [apache2] at time 02:54:10.581408 duration_in_ms=82.322
2019-06-14 02:54:10,584 [salt.state       :1780][INFO    ][13762] Running state [maasng.wait_for_http_code] at time 02:54:10.583977
2019-06-14 02:54:10,584 [salt.state       :1813][INFO    ][13762] Executing state module.run for [maasng.wait_for_http_code]
2019-06-14 02:54:10,585 [salt.utils.decorators:613 ][WARNING ][13762] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:54:10,736 [salt.state       :300 ][INFO    ][13762] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-14 02:54:10,737 [salt.state       :1951][INFO    ][13762] Completed state [maasng.wait_for_http_code] at time 02:54:10.737229 duration_in_ms=153.253
2019-06-14 02:54:10,739 [salt.state       :1780][INFO    ][13762] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:54:10.739544
2019-06-14 02:54:10,739 [salt.state       :1813][INFO    ][13762] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-14 02:54:10,740 [salt.state       :300 ][INFO    ][13762] /var/lib/maas/.setup_admin exists
2019-06-14 02:54:10,740 [salt.state       :1951][INFO    ][13762] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:54:10.740899 duration_in_ms=1.355
2019-06-14 02:54:10,744 [salt.state       :1780][INFO    ][13762] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:10.744547
2019-06-14 02:54:10,744 [salt.state       :1813][INFO    ][13762] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-14 02:54:10,745 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13762] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-14 02:54:12,516 [salt.state       :300 ][INFO    ][13762] {'pid': 13816, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-14 02:54:12,517 [salt.state       :1951][INFO    ][13762] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:12.517439 duration_in_ms=1772.892
2019-06-14 02:54:12,526 [salt.state       :1780][INFO    ][13762] Running state [maas_region_boot_source_resources_mirror] at time 02:54:12.526090
2019-06-14 02:54:12,526 [salt.state       :1813][INFO    ][13762] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-14 02:54:12,634 [salt.state       :300 ][INFO    ][13762] {'changes': {}}
2019-06-14 02:54:12,635 [salt.state       :1951][INFO    ][13762] Completed state [maas_region_boot_source_resources_mirror] at time 02:54:12.635082 duration_in_ms=108.99
2019-06-14 02:54:12,636 [salt.state       :1780][INFO    ][13762] Running state [maasng.boot_resources_import] at time 02:54:12.636116
2019-06-14 02:54:12,636 [salt.state       :1813][INFO    ][13762] Executing state module.run for [maasng.boot_resources_import]
2019-06-14 02:54:12,637 [salt.utils.decorators:613 ][WARNING ][13762] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:54:12,729 [salt.loaded.ext.module.maasng:1600][INFO    ][13762] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-14 02:54:17,772 [salt.loaded.ext.module.maasng:1600][INFO    ][13762] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-14 02:54:19,917 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025419895631
2019-06-14 02:54:19,952 [salt.minion      :1432][INFO    ][13943] Starting a new job with PID 13943
2019-06-14 02:54:20,003 [salt.minion      :1711][INFO    ][13943] Returning information for job: 20190614025419895631
2019-06-14 02:54:22,871 [salt.state       :300 ][INFO    ][13762] {'ret': True}
2019-06-14 02:54:22,871 [salt.state       :1951][INFO    ][13762] Completed state [maasng.boot_resources_import] at time 02:54:22.871701 duration_in_ms=10235.584
2019-06-14 02:54:22,872 [salt.state       :1780][INFO    ][13762] Running state [maas_region_boot_sources_selection_xenial] at time 02:54:22.872779
2019-06-14 02:54:22,873 [salt.state       :1813][INFO    ][13762] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-14 02:54:23,050 [salt.state       :300 ][INFO    ][13762] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-14 02:54:23,050 [salt.state       :1951][INFO    ][13762] Completed state [maas_region_boot_sources_selection_xenial] at time 02:54:23.050665 duration_in_ms=177.886
2019-06-14 02:54:23,051 [salt.state       :1780][INFO    ][13762] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:54:23.051750
2019-06-14 02:54:23,052 [salt.state       :1813][INFO    ][13762] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-14 02:54:23,052 [salt.utils.decorators:613 ][WARNING ][13762] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:54:23,053 [salt.loaded.ext.module.maasng:1771][INFO    ][13762] boot-sources sync initiated for ALL Rack's
2019-06-14 02:54:24,124 [salt.state       :300 ][INFO    ][13762] {'ret': True}
2019-06-14 02:54:24,124 [salt.state       :1951][INFO    ][13762] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:54:24.124862 duration_in_ms=1073.112
2019-06-14 02:54:24,127 [salt.state       :1780][INFO    ][13762] Running state [maas.process_maas_config] at time 02:54:24.127511
2019-06-14 02:54:24,127 [salt.state       :1813][INFO    ][13762] Executing state module.run for [maas.process_maas_config]
2019-06-14 02:54:24,128 [salt.utils.decorators:613 ][WARNING ][13762] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:54:24,129 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=enable_http_proxy value=True
2019-06-14 02:54:24,187 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=upstream_dns value=8.8.8.8
2019-06-14 02:54:24,247 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=commissioning_distro_series value=xenial
2019-06-14 02:54:25,772 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=default_osystem value=ubuntu
2019-06-14 02:54:25,838 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=active_discovery_interval value=600
2019-06-14 02:54:25,891 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=dnssec_validation value=no
2019-06-14 02:54:25,953 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=maas_name value=mas01
2019-06-14 02:54:26,012 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=network_discovery value=enabled
2019-06-14 02:54:26,128 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=enable_third_party_drivers value=True
2019-06-14 02:54:26,186 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=default_storage_layout value=lvm
2019-06-14 02:54:26,235 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=ntp_external_only value=True
2019-06-14 02:54:26,292 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-14 02:54:26,346 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=default_distro_series value=xenial
2019-06-14 02:54:26,423 [salt.loaded.ext.module.maas:92  ][INFO    ][13762] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-06-14 02:54:26,557 [salt.state       :300 ][INFO    ][13762] {'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-06-14 02:54:26,558 [salt.state       :1951][INFO    ][13762] Completed state [maas.process_maas_config] at time 02:54:26.558813 duration_in_ms=2431.301
2019-06-14 02:54:26,559 [salt.state       :1780][INFO    ][13762] Running state [pxe_admin] at time 02:54:26.559497
2019-06-14 02:54:26,559 [salt.state       :1813][INFO    ][13762] Executing state maasng.fabric_present for [pxe_admin]
2019-06-14 02:54:26,619 [salt.loaded.ext.module.maasng:945 ][INFO    ][13762] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-06-14 02:54:26,677 [salt.loaded.ext.module.maasng:1008][WARNING ][13762] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-14 02:54:26,679 [salt.loaded.ext.module.maasng:1011][WARNING ][13762] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-14 02:54:26,735 [salt.state       :300 ][INFO    ][13762] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-14 02:54:26,735 [salt.state       :1951][INFO    ][13762] Completed state [pxe_admin] at time 02:54:26.735925 duration_in_ms=176.428
2019-06-14 02:54:26,736 [salt.state       :1780][INFO    ][13762] Running state [vlan 0] at time 02:54:26.736262
2019-06-14 02:54:26,736 [salt.state       :1813][INFO    ][13762] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-14 02:54:26,788 [salt.loaded.ext.module.maasng:945 ][INFO    ][13762] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-06-14 02:54:26,903 [salt.loaded.ext.module.maasng:945 ][INFO    ][13762] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-06-14 02:54:27,174 [salt.loaded.ext.module.maasng:945 ][INFO    ][13762] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-14 02:54:27,263 [salt.state       :300 ][INFO    ][13762] {'new': 'Vlan untagged was updated'}
2019-06-14 02:54:27,264 [salt.state       :1951][INFO    ][13762] Completed state [vlan 0] at time 02:54:27.264041 duration_in_ms=527.778
2019-06-14 02:54:27,268 [salt.state       :1780][INFO    ][13762] Running state [192.168.11.0/24] at time 02:54:27.268384
2019-06-14 02:54:27,268 [salt.state       :1813][INFO    ][13762] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-14 02:54:27,455 [salt.loaded.ext.module.maasng:945 ][INFO    ][13762] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-06-14 02:54:27,456 [salt.loaded.ext.module.maasng:1235][WARNING ][13762] Ignoring parameter vlan:0
2019-06-14 02:54:27,529 [salt.state       :300 ][INFO    ][13762] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-14 02:54:27,529 [salt.state       :1951][INFO    ][13762] Completed state [192.168.11.0/24] at time 02:54:27.529420 duration_in_ms=261.035
2019-06-14 02:54:27,531 [salt.state       :1780][INFO    ][13762] Running state [maas_create_iprange_1] at time 02:54:27.531503
2019-06-14 02:54:27,531 [salt.state       :1813][INFO    ][13762] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-14 02:54:27,600 [salt.state       :300 ][INFO    ][13762] Iprange maas_create_iprange_1 already exist.
2019-06-14 02:54:27,600 [salt.state       :1951][INFO    ][13762] Completed state [maas_create_iprange_1] at time 02:54:27.600874 duration_in_ms=69.371
2019-06-14 02:54:27,601 [salt.state       :1780][INFO    ][13762] Running state [vlan 0] at time 02:54:27.601199
2019-06-14 02:54:27,601 [salt.state       :1813][INFO    ][13762] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-14 02:54:27,654 [salt.loaded.ext.module.maasng:945 ][INFO    ][13762] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'744x48', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-14 02:54:27,769 [salt.loaded.ext.module.maasng:945 ][INFO    ][13762] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'744x48', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-14 02:54:28,021 [salt.loaded.ext.module.maasng:945 ][INFO    ][13762] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'744x48', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-06-14 02:54:28,172 [salt.state       :300 ][INFO    ][13762] {'new': 'Vlan untagged was updated'}
2019-06-14 02:54:28,172 [salt.state       :1951][INFO    ][13762] Completed state [vlan 0] at time 02:54:28.172688 duration_in_ms=571.488
2019-06-14 02:54:28,173 [salt.state       :1780][INFO    ][13762] Running state [opnfv] at time 02:54:28.173861
2019-06-14 02:54:28,174 [salt.state       :1813][INFO    ][13762] Executing state maasng.sshkey_present for [opnfv]
2019-06-14 02:54:28,222 [salt.loaded.ext.module.maasng:1903][INFO    ][13762] [{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-06-14 02:54:28,223 [salt.state       :300 ][INFO    ][13762] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-14 02:54:28,223 [salt.state       :1951][INFO    ][13762] Completed state [opnfv] at time 02:54:28.223402 duration_in_ms=49.542
2019-06-14 02:54:28,227 [salt.minion      :1711][INFO    ][13762] Returning information for job: 20190614025404771130
2019-06-14 02:54:28,835 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614025428825554
2019-06-14 02:54:28,867 [salt.minion      :1432][INFO    ][14232] Starting a new job with PID 14232
2019-06-14 02:54:37,062 [salt.state       :915 ][INFO    ][14232] Loading fresh modules for state activity
2019-06-14 02:54:37,172 [salt.state       :1780][INFO    ][14232] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:37.171934
2019-06-14 02:54:37,172 [salt.state       :1813][INFO    ][14232] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-14 02:54:37,174 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14232] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-14 02:54:38,932 [salt.state       :300 ][INFO    ][14232] {'pid': 14261, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-14 02:54:38,933 [salt.state       :1951][INFO    ][14232] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:54:38.933492 duration_in_ms=1761.557
2019-06-14 02:54:38,937 [salt.state       :1780][INFO    ][14232] Running state [maas.process_machines] at time 02:54:38.937155
2019-06-14 02:54:38,937 [salt.state       :1813][INFO    ][14232] Executing state module.run for [maas.process_machines]
2019-06-14 02:54:38,938 [salt.utils.decorators:613 ][WARNING ][14232] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:54:39,509 [salt.loaded.ext.module.maas:412 ][WARNING ][14232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-14 02:54:39,510 [salt.loaded.ext.module.maas:92  ][INFO    ][14232] 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=w8akqy architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-14 02:54:40,731 [salt.loaded.ext.module.maas:412 ][WARNING ][14232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-14 02:54:40,732 [salt.loaded.ext.module.maas:92  ][INFO    ][14232] 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=wrgcf3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-14 02:54:42,085 [salt.loaded.ext.module.maas:412 ][WARNING ][14232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-14 02:54:42,088 [salt.loaded.ext.module.maas:92  ][INFO    ][14232] 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=p4edbt architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-14 02:54:43,307 [salt.loaded.ext.module.maas:412 ][WARNING ][14232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-14 02:54:43,308 [salt.loaded.ext.module.maas:92  ][INFO    ][14232] 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=hxxbg4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-14 02:54:43,935 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025443922711
2019-06-14 02:54:43,954 [salt.minion      :1432][INFO    ][14423] Starting a new job with PID 14423
2019-06-14 02:54:43,986 [salt.minion      :1711][INFO    ][14423] Returning information for job: 20190614025443922711
2019-06-14 02:54:44,531 [salt.loaded.ext.module.maas:412 ][WARNING ][14232] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-14 02:54:44,532 [salt.loaded.ext.module.maas:92  ][INFO    ][14232] 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=cgbbg3 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-14 02:54:45,705 [salt.state       :300 ][INFO    ][14232] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-14 02:54:45,706 [salt.state       :1951][INFO    ][14232] Completed state [maas.process_machines] at time 02:54:45.705666 duration_in_ms=6768.509
2019-06-14 02:54:45,712 [salt.minion      :1711][INFO    ][14232] Returning information for job: 20190614025428825554
2019-06-14 02:55:18,740 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614025518724496
2019-06-14 02:55:18,767 [salt.minion      :1432][INFO    ][14509] Starting a new job with PID 14509
2019-06-14 02:55:26,766 [salt.state       :915 ][INFO    ][14509] Loading fresh modules for state activity
2019-06-14 02:55:26,884 [salt.state       :1780][INFO    ][14509] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:26.884576
2019-06-14 02:55:26,885 [salt.state       :1813][INFO    ][14509] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-14 02:55:26,887 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14509] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-14 02:55:28,605 [salt.state       :300 ][INFO    ][14509] {'pid': 14546, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-14 02:55:28,606 [salt.state       :1951][INFO    ][14509] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:28.606434 duration_in_ms=1721.858
2019-06-14 02:55:28,610 [salt.state       :1780][INFO    ][14509] Running state [maas.wait_for_machine_status] at time 02:55:28.610127
2019-06-14 02:55:28,610 [salt.state       :1813][INFO    ][14509] Executing state module.run for [maas.wait_for_machine_status]
2019-06-14 02:55:28,611 [salt.utils.decorators:613 ][WARNING ][14509] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:55:31,357 [salt.state       :300 ][INFO    ][14509] {'ret': True}
2019-06-14 02:55:31,360 [salt.state       :1951][INFO    ][14509] Completed state [maas.wait_for_machine_status] at time 02:55:31.360176 duration_in_ms=2750.046
2019-06-14 02:55:31,364 [salt.minion      :1711][INFO    ][14509] Returning information for job: 20190614025518724496
2019-06-14 02:55:31,988 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614025531924637
2019-06-14 02:55:32,016 [salt.minion      :1432][INFO    ][14565] Starting a new job with PID 14565
2019-06-14 02:55:33,116 [salt.state       :915 ][INFO    ][14565] Loading fresh modules for state activity
2019-06-14 02:55:33,260 [salt.state       :1780][INFO    ][14565] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:33.260540
2019-06-14 02:55:33,260 [salt.state       :1813][INFO    ][14565] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-14 02:55:33,262 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14565] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-14 02:55:34,978 [salt.state       :300 ][INFO    ][14565] {'pid': 14572, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-14 02:55:34,979 [salt.state       :1951][INFO    ][14565] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:34.979766 duration_in_ms=1719.225
2019-06-14 02:55:34,983 [salt.state       :1780][INFO    ][14565] Running state [maas_machines_storage_cmp002_lvm] at time 02:55:34.983714
2019-06-14 02:55:34,984 [salt.state       :1813][INFO    ][14565] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-14 02:55:35,541 [salt.state       :300 ][INFO    ][14565] Machine cmp002 is not in Ready state.
2019-06-14 02:55:35,542 [salt.state       :1951][INFO    ][14565] Completed state [maas_machines_storage_cmp002_lvm] at time 02:55:35.542120 duration_in_ms=558.406
2019-06-14 02:55:35,542 [salt.state       :1780][INFO    ][14565] Running state [maas_machines_storage_cmp001_lvm] at time 02:55:35.542513
2019-06-14 02:55:35,542 [salt.state       :1813][INFO    ][14565] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-14 02:55:36,188 [salt.state       :300 ][INFO    ][14565] Machine cmp001 is not in Ready state.
2019-06-14 02:55:36,188 [salt.state       :1951][INFO    ][14565] Completed state [maas_machines_storage_cmp001_lvm] at time 02:55:36.188542 duration_in_ms=646.028
2019-06-14 02:55:36,192 [salt.minion      :1711][INFO    ][14565] Returning information for job: 20190614025531924637
2019-06-14 02:55:36,749 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614025536735104
2019-06-14 02:55:36,775 [salt.minion      :1432][INFO    ][14582] Starting a new job with PID 14582
2019-06-14 02:55:37,952 [salt.state       :915 ][INFO    ][14582] Loading fresh modules for state activity
2019-06-14 02:55:38,050 [salt.state       :1780][INFO    ][14582] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:38.050116
2019-06-14 02:55:38,050 [salt.state       :1813][INFO    ][14582] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-14 02:55:38,052 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14582] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-14 02:55:39,752 [salt.state       :300 ][INFO    ][14582] {'pid': 14589, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-14 02:55:39,752 [salt.state       :1951][INFO    ][14582] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:39.752631 duration_in_ms=1702.515
2019-06-14 02:55:39,754 [salt.state       :1780][INFO    ][14582] Running state [maas.deploy_machines] at time 02:55:39.754142
2019-06-14 02:55:39,754 [salt.state       :1813][INFO    ][14582] Executing state module.run for [maas.deploy_machines]
2019-06-14 02:55:39,754 [salt.utils.decorators:613 ][WARNING ][14582] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:55:40,312 [salt.loaded.ext.module.maas:684 ][INFO    ][14582] deploymachines hwe_kernel=ga-16.04 system_id=cgbbg3 distro_series=xenial
2019-06-14 02:55:42,812 [salt.state       :300 ][INFO    ][14582] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-14 02:55:42,812 [salt.state       :1951][INFO    ][14582] Completed state [maas.deploy_machines] at time 02:55:42.812516 duration_in_ms=3058.372
2019-06-14 02:55:42,817 [salt.minion      :1711][INFO    ][14582] Returning information for job: 20190614025536735104
2019-06-14 02:55:43,392 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command state.apply with jid 20190614025543383088
2019-06-14 02:55:43,422 [salt.minion      :1432][INFO    ][14649] Starting a new job with PID 14649
2019-06-14 02:55:51,473 [salt.state       :915 ][INFO    ][14649] Loading fresh modules for state activity
2019-06-14 02:55:51,571 [salt.state       :1780][INFO    ][14649] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:51.571429
2019-06-14 02:55:51,571 [salt.state       :1813][INFO    ][14649] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-14 02:55:51,573 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14649] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-14 02:55:53,275 [salt.state       :300 ][INFO    ][14649] {'pid': 14666, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-14 02:55:53,276 [salt.state       :1951][INFO    ][14649] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:55:53.276285 duration_in_ms=1704.854
2019-06-14 02:55:53,280 [salt.state       :1780][INFO    ][14649] Running state [maas.wait_for_machine_status] at time 02:55:53.280372
2019-06-14 02:55:53,281 [salt.state       :1813][INFO    ][14649] Executing state module.run for [maas.wait_for_machine_status]
2019-06-14 02:55:53,283 [salt.utils.decorators:613 ][WARNING ][14649] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-14 02:55:56,092 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.21195102s left)
2019-06-14 02:55:58,448 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025558436833
2019-06-14 02:55:58,479 [salt.minion      :1432][INFO    ][14677] Starting a new job with PID 14677
2019-06-14 02:55:58,504 [salt.minion      :1711][INFO    ][14677] Returning information for job: 20190614025558436833
2019-06-14 02:56:28,528 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025628512969
2019-06-14 02:56:28,547 [salt.minion      :1432][INFO    ][14735] Starting a new job with PID 14735
2019-06-14 02:56:28,571 [salt.minion      :1711][INFO    ][14735] Returning information for job: 20190614025628512969
2019-06-14 02:56:28,880 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.42329693s left)
2019-06-14 02:56:58,605 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025658591061
2019-06-14 02:56:58,637 [salt.minion      :1432][INFO    ][14749] Starting a new job with PID 14749
2019-06-14 02:56:58,667 [salt.minion      :1711][INFO    ][14749] Returning information for job: 20190614025658591061
2019-06-14 02:57:01,940 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.36373901s left)
2019-06-14 02:57:28,712 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025728697449
2019-06-14 02:57:28,741 [salt.minion      :1432][INFO    ][14825] Starting a new job with PID 14825
2019-06-14 02:57:28,763 [salt.minion      :1711][INFO    ][14825] Returning information for job: 20190614025728697449
2019-06-14 02:57:34,522 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.78116989s left)
2019-06-14 02:57:58,795 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025758782329
2019-06-14 02:57:58,824 [salt.minion      :1432][INFO    ][14846] Starting a new job with PID 14846
2019-06-14 02:57:58,846 [salt.minion      :1711][INFO    ][14846] Returning information for job: 20190614025758782329
2019-06-14 02:58:07,257 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.04660392s left)
2019-06-14 02:58:28,894 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025828879447
2019-06-14 02:58:28,925 [salt.minion      :1432][INFO    ][14910] Starting a new job with PID 14910
2019-06-14 02:58:28,950 [salt.minion      :1711][INFO    ][14910] Returning information for job: 20190614025828879447
2019-06-14 02:58:39,932 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.37196088s left)
2019-06-14 02:58:58,978 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025858963830
2019-06-14 02:58:59,008 [salt.minion      :1432][INFO    ][14933] Starting a new job with PID 14933
2019-06-14 02:58:59,033 [salt.minion      :1711][INFO    ][14933] Returning information for job: 20190614025858963830
2019-06-14 02:59:12,788 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.5154798s left)
2019-06-14 02:59:29,085 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025929067762
2019-06-14 02:59:29,118 [salt.minion      :1432][INFO    ][14993] Starting a new job with PID 14993
2019-06-14 02:59:29,143 [salt.minion      :1711][INFO    ][14993] Returning information for job: 20190614025929067762
2019-06-14 02:59:45,678 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.62633181s left)
2019-06-14 02:59:59,180 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614025959170968
2019-06-14 02:59:59,205 [salt.minion      :1432][INFO    ][15037] Starting a new job with PID 15037
2019-06-14 02:59:59,228 [salt.minion      :1711][INFO    ][15037] Returning information for job: 20190614025959170968
2019-06-14 03:00:18,934 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.36991096s left)
2019-06-14 03:00:29,284 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030029266537
2019-06-14 03:00:29,313 [salt.minion      :1432][INFO    ][15150] Starting a new job with PID 15150
2019-06-14 03:00:29,335 [salt.minion      :1711][INFO    ][15150] Returning information for job: 20190614030029266537
2019-06-14 03:00:51,732 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.57148981s left)
2019-06-14 03:00:59,393 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030059380084
2019-06-14 03:00:59,420 [salt.minion      :1432][INFO    ][15175] Starting a new job with PID 15175
2019-06-14 03:00:59,443 [salt.minion      :1711][INFO    ][15175] Returning information for job: 20190614030059380084
2019-06-14 03:01:24,628 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.67610192s left)
2019-06-14 03:01:29,541 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030129530468
2019-06-14 03:01:29,568 [salt.minion      :1432][INFO    ][15364] Starting a new job with PID 15364
2019-06-14 03:01:29,600 [salt.minion      :1711][INFO    ][15364] Returning information for job: 20190614030129530468
2019-06-14 03:01:57,574 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.72944283s left)
2019-06-14 03:01:59,663 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030159654727
2019-06-14 03:01:59,685 [salt.minion      :1432][INFO    ][15388] Starting a new job with PID 15388
2019-06-14 03:01:59,708 [salt.minion      :1711][INFO    ][15388] Returning information for job: 20190614030159654727
2019-06-14 03:02:29,789 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030229774927
2019-06-14 03:02:29,812 [salt.minion      :1432][INFO    ][15539] Starting a new job with PID 15539
2019-06-14 03:02:29,835 [salt.minion      :1711][INFO    ][15539] Returning information for job: 20190614030229774927
2019-06-14 03:02:30,580 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.72371888s left)
2019-06-14 03:02:59,935 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030259918786
2019-06-14 03:02:59,963 [salt.minion      :1432][INFO    ][15563] Starting a new job with PID 15563
2019-06-14 03:02:59,986 [salt.minion      :1711][INFO    ][15563] Returning information for job: 20190614030259918786
2019-06-14 03:03:03,311 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.99300981s left)
2019-06-14 03:03:30,072 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030330054333
2019-06-14 03:03:30,105 [salt.minion      :1432][INFO    ][15712] Starting a new job with PID 15712
2019-06-14 03:03:30,127 [salt.minion      :1711][INFO    ][15712] Returning information for job: 20190614030330054333
2019-06-14 03:03:36,115 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.18901086s left)
2019-06-14 03:04:00,227 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030400209970
2019-06-14 03:04:00,256 [salt.minion      :1432][INFO    ][15736] Starting a new job with PID 15736
2019-06-14 03:04:00,279 [salt.minion      :1711][INFO    ][15736] Returning information for job: 20190614030400209970
2019-06-14 03:04:08,923 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.38018298s left)
2019-06-14 03:04:30,370 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030430358193
2019-06-14 03:04:30,401 [salt.minion      :1432][INFO    ][15794] Starting a new job with PID 15794
2019-06-14 03:04:30,427 [salt.minion      :1711][INFO    ][15794] Returning information for job: 20190614030430358193
2019-06-14 03:04:41,947 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.35622287s left)
2019-06-14 03:05:00,535 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030500519885
2019-06-14 03:05:00,567 [salt.minion      :1432][INFO    ][15813] Starting a new job with PID 15813
2019-06-14 03:05:00,592 [salt.minion      :1711][INFO    ][15813] Returning information for job: 20190614030500519885
2019-06-14 03:05:14,913 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1688.39020491s left)
2019-06-14 03:05:30,701 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030530683132
2019-06-14 03:05:30,729 [salt.minion      :1432][INFO    ][15869] Starting a new job with PID 15869
2019-06-14 03:05:30,755 [salt.minion      :1711][INFO    ][15869] Returning information for job: 20190614030530683132
2019-06-14 03:05:47,740 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1655.56348896s left)
2019-06-14 03:06:00,871 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030600855795
2019-06-14 03:06:00,901 [salt.minion      :1432][INFO    ][15889] Starting a new job with PID 15889
2019-06-14 03:06:00,924 [salt.minion      :1711][INFO    ][15889] Returning information for job: 20190614030600855795
2019-06-14 03:06:20,696 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1622.6072309s left)
2019-06-14 03:06:31,042 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030631027103
2019-06-14 03:06:31,073 [salt.minion      :1432][INFO    ][15948] Starting a new job with PID 15948
2019-06-14 03:06:31,099 [salt.minion      :1711][INFO    ][15948] Returning information for job: 20190614030631027103
2019-06-14 03:06:53,693 [salt.loaded.ext.module.maas:1023][INFO    ][14649] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1589.610888s left)
2019-06-14 03:07:01,229 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command saltutil.find_job with jid 20190614030701219007
2019-06-14 03:07:01,259 [salt.minion      :1432][INFO    ][15969] Starting a new job with PID 15969
2019-06-14 03:07:01,279 [salt.minion      :1711][INFO    ][15969] Returning information for job: 20190614030701219007
2019-06-14 03:07:26,772 [salt.state       :300 ][INFO    ][14649] {'ret': True}
2019-06-14 03:07:26,773 [salt.state       :1951][INFO    ][14649] Completed state [maas.wait_for_machine_status] at time 03:07:26.773096 duration_in_ms=693492.723
2019-06-14 03:07:26,779 [salt.minion      :1711][INFO    ][14649] Returning information for job: 20190614025543383088
2019-06-14 03:08:16,254 [salt.utils.schedule:1377][INFO    ][5435] Running scheduled job: __mine_interval
2019-06-14 04:08:16,254 [salt.utils.schedule:1377][INFO    ][5435] Running scheduled job: __mine_interval
2019-06-14 04:39:11,917 [salt.minion      :1308][INFO    ][5435] User sudo_ubuntu Executing command cp.push_dir with jid 20190614043911900954
2019-06-14 04:39:11,949 [salt.minion      :1432][INFO    ][22662] Starting a new job with PID 22662
