2019-06-10 02:04:29,638 [salt.utils.decorators:613 ][WARNING ][2054] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:04:30,656 [salt.utils.decorators:613 ][WARNING ][2054] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:04:33,598 [salt.loaded.int.states.file:2298][WARNING ][2445] 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-10 02:04:54,027 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2952] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-10 02:04:54,048 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2952] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-10 02:04:54,091 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-06-10 02:04:55,224 [salt.cli.daemons :293 ][INFO    ][3058] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-06-10 02:04:55,352 [salt.cli.daemons :82  ][INFO    ][3058] Starting up the Salt Minion
2019-06-10 02:04:55,353 [salt.utils.event :1017][INFO    ][3058] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-06-10 02:04:56,451 [salt.minion      :976 ][INFO    ][3058] Creating minion process manager
2019-06-10 02:04:58,375 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3058] Executing command ['date', '+%z'] in directory '/root'
2019-06-10 02:04:58,395 [salt.utils.schedule:568 ][INFO    ][3058] Updating job settings for scheduled job: __mine_interval
2019-06-10 02:04:58,397 [salt.minion      :1108][INFO    ][3058] Added mine.update to scheduler
2019-06-10 02:04:58,403 [salt.minion      :1975][INFO    ][3058] Minion is starting as user 'root'
2019-06-10 02:04:58,422 [salt.minion      :2336][INFO    ][3058] Minion is ready to receive requests!
2019-06-10 02:05:03,419 [salt.state       :2022][WARNING ][2959] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-10 02:05:06,489 [salt.utils.decorators:613 ][WARNING ][2959] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:05:07,328 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610020507314732
2019-06-10 02:05:07,351 [salt.minion      :1432][INFO    ][3602] Starting a new job with PID 3602
2019-06-10 02:05:07,375 [salt.minion      :1711][INFO    ][3602] Returning information for job: 20190610020507314732
2019-06-10 02:05:17,872 [salt.utils.decorators:613 ][WARNING ][2959] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:05:37,461 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610020537451059
2019-06-10 02:05:37,483 [salt.minion      :1432][INFO    ][4242] Starting a new job with PID 4242
2019-06-10 02:05:37,504 [salt.minion      :1711][INFO    ][4242] Returning information for job: 20190610020537451059
2019-06-10 02:05:44,732 [salt.utils.decorators:613 ][WARNING ][2959] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:05:45,722 [salt.utils.decorators:613 ][WARNING ][2959] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:05:47,669 [salt.loaded.ext.module.maasng:1008][WARNING ][2959] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-06-10 02:05:47,669 [salt.loaded.ext.module.maasng:1011][WARNING ][2959] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-06-10 02:05:48,389 [salt.loaded.ext.module.maasng:1235][WARNING ][2959] Ignoring parameter vlan:0
2019-06-10 02:05:49,888 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610020549877644
2019-06-10 02:05:49,904 [salt.minion      :1432][INFO    ][4611] Starting a new job with PID 4611
2019-06-10 02:05:56,031 [salt.state       :915 ][INFO    ][4611] Loading fresh modules for state activity
2019-06-10 02:05:56,088 [salt.fileclient  :1219][INFO    ][4611] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-06-10 02:05:56,134 [salt.state       :1780][INFO    ][4611] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:05:56.134263
2019-06-10 02:05:56,134 [salt.state       :1813][INFO    ][4611] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-10 02:05:56,136 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4611] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-10 02:05:57,852 [salt.state       :300 ][INFO    ][4611] {'pid': 4654, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-10 02:05:57,853 [salt.state       :1951][INFO    ][4611] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:05:57.853111 duration_in_ms=1718.848
2019-06-10 02:05:57,856 [salt.state       :1780][INFO    ][4611] Running state [maas.process_machines] at time 02:05:57.856734
2019-06-10 02:05:57,857 [salt.state       :1813][INFO    ][4611] Executing state module.run for [maas.process_machines]
2019-06-10 02:05:57,858 [salt.utils.decorators:613 ][WARNING ][4611] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:05:57,920 [salt.loaded.ext.module.maas:412 ][WARNING ][4611] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-10 02:05:57,921 [salt.loaded.ext.module.maas:92  ][INFO    ][4611] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-10 02:05:59,375 [salt.loaded.ext.module.maas:412 ][WARNING ][4611] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-10 02:05:59,376 [salt.loaded.ext.module.maas:92  ][INFO    ][4611] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-10 02:06:00,712 [salt.loaded.ext.module.maas:412 ][WARNING ][4611] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-10 02:06:00,712 [salt.loaded.ext.module.maas:92  ][INFO    ][4611] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-10 02:06:01,993 [salt.loaded.ext.module.maas:412 ][WARNING ][4611] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-10 02:06:01,998 [salt.loaded.ext.module.maas:92  ][INFO    ][4611] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-10 02:06:03,330 [salt.loaded.ext.module.maas:412 ][WARNING ][4611] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-10 02:06:03,331 [salt.loaded.ext.module.maas:92  ][INFO    ][4611] 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 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-10 02:06:04,780 [salt.state       :300 ][INFO    ][4611] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-10 02:06:04,781 [salt.state       :1951][INFO    ][4611] Completed state [maas.process_machines] at time 02:06:04.781257 duration_in_ms=6924.523
2019-06-10 02:06:04,784 [salt.minion      :1711][INFO    ][4611] Returning information for job: 20190610020549877644
2019-06-10 02:06:35,831 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610020635820415
2019-06-10 02:06:35,851 [salt.minion      :1432][INFO    ][4989] Starting a new job with PID 4989
2019-06-10 02:06:41,853 [salt.state       :915 ][INFO    ][4989] Loading fresh modules for state activity
2019-06-10 02:06:41,911 [salt.fileclient  :1219][INFO    ][4989] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-10 02:06:41,959 [salt.state       :1780][INFO    ][4989] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:41.959229
2019-06-10 02:06:41,959 [salt.state       :1813][INFO    ][4989] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-10 02:06:41,961 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4989] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-10 02:06:43,712 [salt.state       :300 ][INFO    ][4989] {'pid': 5001, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-10 02:06:43,712 [salt.state       :1951][INFO    ][4989] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:06:43.712512 duration_in_ms=1753.283
2019-06-10 02:06:43,715 [salt.state       :1780][INFO    ][4989] Running state [maas.wait_for_machine_status] at time 02:06:43.715021
2019-06-10 02:06:43,715 [salt.state       :1813][INFO    ][4989] Executing state module.run for [maas.wait_for_machine_status]
2019-06-10 02:06:43,715 [salt.utils.decorators:613 ][WARNING ][4989] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:06:44,728 [salt.loaded.ext.module.maas:1023][INFO    ][4989] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.99568701s left)
2019-06-10 02:06:50,937 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610020650918579
2019-06-10 02:06:50,960 [salt.minion      :1432][INFO    ][5012] Starting a new job with PID 5012
2019-06-10 02:06:50,982 [salt.minion      :1711][INFO    ][5012] Returning information for job: 20190610020650918579
2019-06-10 02:07:15,752 [salt.loaded.ext.module.maas:1023][INFO    ][4989] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.97178102s left)
2019-06-10 02:07:21,019 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610020721004795
2019-06-10 02:07:21,046 [salt.minion      :1432][INFO    ][5091] Starting a new job with PID 5091
2019-06-10 02:07:21,068 [salt.minion      :1711][INFO    ][5091] Returning information for job: 20190610020721004795
2019-06-10 02:07:46,611 [salt.loaded.ext.module.maas:1023][INFO    ][4989] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.11223388s left)
2019-06-10 02:07:51,093 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610020751084084
2019-06-10 02:07:51,121 [salt.minion      :1432][INFO    ][5115] Starting a new job with PID 5115
2019-06-10 02:07:51,144 [salt.minion      :1711][INFO    ][5115] Returning information for job: 20190610020751084084
2019-06-10 02:08:17,395 [salt.loaded.ext.module.maas:1023][INFO    ][4989] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.32821202s left)
2019-06-10 02:08:21,184 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610020821168246
2019-06-10 02:08:21,201 [salt.minion      :1432][INFO    ][5167] Starting a new job with PID 5167
2019-06-10 02:08:21,222 [salt.minion      :1711][INFO    ][5167] Returning information for job: 20190610020821168246
2019-06-10 02:08:48,468 [salt.loaded.ext.module.maas:1023][INFO    ][4989] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.25510001s left)
2019-06-10 02:08:51,232 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610020851222720
2019-06-10 02:08:51,255 [salt.minion      :1432][INFO    ][5218] Starting a new job with PID 5218
2019-06-10 02:08:51,277 [salt.minion      :1711][INFO    ][5218] Returning information for job: 20190610020851222720
2019-06-10 02:09:19,793 [salt.loaded.ext.module.maas:1023][INFO    ][4989] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.93067789s left)
2019-06-10 02:09:21,324 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610020921307808
2019-06-10 02:09:21,348 [salt.minion      :1432][INFO    ][5370] Starting a new job with PID 5370
2019-06-10 02:09:21,379 [salt.minion      :1711][INFO    ][5370] Returning information for job: 20190610020921307808
2019-06-10 02:09:51,135 [salt.loaded.ext.module.maas:1023][INFO    ][4989] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.58891511s left)
2019-06-10 02:09:51,420 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610020951410950
2019-06-10 02:09:51,448 [salt.minion      :1432][INFO    ][5493] Starting a new job with PID 5493
2019-06-10 02:09:51,476 [salt.minion      :1711][INFO    ][5493] Returning information for job: 20190610020951410950
2019-06-10 02:10:21,522 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021021511017
2019-06-10 02:10:21,537 [salt.minion      :1432][INFO    ][5766] Starting a new job with PID 5766
2019-06-10 02:10:21,563 [salt.minion      :1711][INFO    ][5766] Returning information for job: 20190610021021511017
2019-06-10 02:10:22,595 [salt.loaded.ext.module.maas:1023][INFO    ][4989] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.12851906s left)
2019-06-10 02:10:51,637 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021051622638
2019-06-10 02:10:51,663 [salt.minion      :1432][INFO    ][5889] Starting a new job with PID 5889
2019-06-10 02:10:51,690 [salt.minion      :1711][INFO    ][5889] Returning information for job: 20190610021051622638
2019-06-10 02:10:54,396 [salt.loaded.ext.module.maas:1023][INFO    ][4989] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.32744694s left)
2019-06-10 02:11:21,836 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021121825025
2019-06-10 02:11:21,862 [salt.minion      :1432][INFO    ][6360] Starting a new job with PID 6360
2019-06-10 02:11:21,896 [salt.minion      :1711][INFO    ][6360] Returning information for job: 20190610021121825025
2019-06-10 02:11:26,672 [salt.loaded.ext.module.maas:1023][INFO    ][4989] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.05158901s left)
2019-06-10 02:11:51,965 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021151947751
2019-06-10 02:11:51,994 [salt.minion      :1432][INFO    ][6489] Starting a new job with PID 6489
2019-06-10 02:11:52,024 [salt.minion      :1711][INFO    ][6489] Returning information for job: 20190610021151947751
2019-06-10 02:11:58,907 [salt.loaded.ext.module.maas:1023][INFO    ][4989] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.816396s left)
2019-06-10 02:12:22,111 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021222096648
2019-06-10 02:12:22,139 [salt.minion      :1432][INFO    ][6634] Starting a new job with PID 6634
2019-06-10 02:12:22,168 [salt.minion      :1711][INFO    ][6634] Returning information for job: 20190610021222096648
2019-06-10 02:12:31,809 [salt.state       :300 ][INFO    ][4989] {'ret': True}
2019-06-10 02:12:31,810 [salt.state       :1951][INFO    ][4989] Completed state [maas.wait_for_machine_status] at time 02:12:31.810468 duration_in_ms=348095.445
2019-06-10 02:12:31,816 [salt.minion      :1711][INFO    ][4989] Returning information for job: 20190610020635820415
2019-06-10 02:12:32,536 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610021232524040
2019-06-10 02:12:32,559 [salt.minion      :1432][INFO    ][6650] Starting a new job with PID 6650
2019-06-10 02:12:38,669 [salt.state       :915 ][INFO    ][6650] Loading fresh modules for state activity
2019-06-10 02:12:38,731 [salt.fileclient  :1219][INFO    ][6650] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-10 02:12:38,839 [salt.state       :1780][INFO    ][6650] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:38.839567
2019-06-10 02:12:38,839 [salt.state       :1813][INFO    ][6650] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-10 02:12:38,842 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6650] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-10 02:12:40,844 [salt.state       :300 ][INFO    ][6650] {'pid': 6691, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-10 02:12:40,846 [salt.state       :1951][INFO    ][6650] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:40.846338 duration_in_ms=2006.772
2019-06-10 02:12:40,849 [salt.state       :1780][INFO    ][6650] Running state [maas_machines_storage_cmp002_lvm] at time 02:12:40.849496
2019-06-10 02:12:40,850 [salt.state       :1813][INFO    ][6650] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-10 02:12:41,989 [salt.loaded.ext.module.maasng:610 ][INFO    ][6650] p78wqg
2019-06-10 02:12:41,989 [salt.loaded.ext.module.maasng:626 ][INFO    ][6650] sda
2019-06-10 02:12:42,568 [salt.loaded.ext.module.maasng:361 ][INFO    ][6650] p78wqg
2019-06-10 02:12:42,655 [salt.loaded.ext.module.maasng:367 ][INFO    ][6650] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/blockdevices/1/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'p78wqg', 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'block_size': 4096, u'type': u'physical', u'id': 1, u'partitions': [{u'size': 800101236736, u'uuid': u'd6cf038e-56b7-46a5-ac74-9a59dc702a25', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'p78wqg', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0e56360f-3f8c-4918-8a3b-6cf8eefcaf1b', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}]}, {u'size': 800097042432, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/blockdevices/3/', u'used_size': 800097042432, u'tags': [], u'uuid': u'd4247d0f-6d2d-4d99-b94c-2fd5be3cc88e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'p78wqg', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1ab8de75-fa4b-4781-9c92-89fd63fd6a05', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'partitions': []}]
2019-06-10 02:12:42,655 [salt.loaded.ext.module.maasng:632 ][INFO    ][6650] vgroot
2019-06-10 02:12:42,656 [salt.loaded.ext.module.maasng:635 ][INFO    ][6650] lvroot
2019-06-10 02:12:42,656 [salt.loaded.ext.module.maasng:639 ][INFO    ][6650] 107374182400
2019-06-10 02:12:43,228 [salt.loaded.ext.module.maasng:645 ][INFO    ][6650] {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'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'p78wqg', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/interfaces/4/'}, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'fqdn': u'cmp002.maas', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'p78wqg', 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'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'partitions': [], u'uuid': u'0938801f-3bc3-4907-84b6-754cf3263ffd', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'p78wqg', u'partition_table_type': None, u'filesystem': {u'uuid': u'27165f67-89ac-449c-8b0b-5fd53874a292', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/blockdevices/11/', u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'ab0aa122-9eb8-4122-b1cf-d41ace6cfa2e', u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'p78wqg', u'filesystem': {u'uuid': u'00a43177-5988-4361-bb59-bd94b42a18c4', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'p78wqg', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'0938801f-3bc3-4907-84b6-754cf3263ffd', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'p78wqg', u'partition_table_type': None, u'filesystem': {u'uuid': u'27165f67-89ac-449c-8b0b-5fd53874a292', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/blockdevices/11/', u'type': u'virtual', u'id': 11, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'p78wqg', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'ab0aa122-9eb8-4122-b1cf-d41ace6cfa2e', u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'p78wqg', u'filesystem': {u'uuid': u'00a43177-5988-4361-bb59-bd94b42a18c4', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'p78wqg', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/blockdevices/1/', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'ab0aa122-9eb8-4122-b1cf-d41ace6cfa2e', u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'p78wqg', u'filesystem': {u'uuid': u'00a43177-5988-4361-bb59-bd94b42a18c4', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'p78wqg', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/blockdevices/1/', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'p78wqg', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 14, u'discovered': None, u'parents': [], u'system_id': u'p78wqg', u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/interfaces/14/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 13, u'discovered': None, u'parents': [], u'system_id': u'p78wqg', u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/interfaces/13/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 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': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'system_id': u'p78wqg', u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/interfaces/15/'}, {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'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 11, u'discovered': None, u'parents': [], u'system_id': u'p78wqg', u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/interfaces/11/'}, {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'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 12, u'discovered': None, u'parents': [], u'system_id': u'p78wqg', u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p78wqg/interfaces/12/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/p78wqg/', u'distro_series': u'', u'memory_test_status': -1}
2019-06-10 02:12:43,231 [salt.state       :300 ][INFO    ][6650] {'new': {'storage_layout': 'lvm'}}
2019-06-10 02:12:43,231 [salt.state       :1951][INFO    ][6650] Completed state [maas_machines_storage_cmp002_lvm] at time 02:12:43.231758 duration_in_ms=2382.26
2019-06-10 02:12:43,232 [salt.state       :1780][INFO    ][6650] Running state [maas_machines_storage_cmp001_lvm] at time 02:12:43.232519
2019-06-10 02:12:43,233 [salt.state       :1813][INFO    ][6650] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-10 02:12:44,443 [salt.loaded.ext.module.maasng:610 ][INFO    ][6650] m4dpge
2019-06-10 02:12:44,444 [salt.loaded.ext.module.maasng:626 ][INFO    ][6650] sda
2019-06-10 02:12:44,962 [salt.loaded.ext.module.maasng:361 ][INFO    ][6650] m4dpge
2019-06-10 02:12:45,044 [salt.loaded.ext.module.maasng:367 ][INFO    ][6650] [{u'partition_table_type': u'MBR', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'3dc4dc7f-2240-4acf-b78b-720607556bce', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm4dpge', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'db38a404-b508-475d-8e42-dc84e6de49d1', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/blockdevices/2/partition/2'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'm4dpge', u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'partition_table_type': None, u'available_size': 0, u'uuid': u'45383e32-8f5f-437c-ba4d-16d72327de5f', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'm4dpge', u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/blockdevices/4/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'e25dd5fc-1e6f-4932-8a7c-51f911a907ad', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'size': 800097042432}]
2019-06-10 02:12:45,045 [salt.loaded.ext.module.maasng:632 ][INFO    ][6650] vgroot
2019-06-10 02:12:45,045 [salt.loaded.ext.module.maasng:635 ][INFO    ][6650] lvroot
2019-06-10 02:12:45,045 [salt.loaded.ext.module.maasng:639 ][INFO    ][6650] 107374182400
2019-06-10 02:12:45,625 [salt.loaded.ext.module.maasng:645 ][INFO    ][6650] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'm4dpge', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/interfaces/5/'}, u'disable_ipv4': False, u'cpu_count': 40, 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'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.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'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'm4dpge', 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.42'], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/blockdevices/2/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'm4dpge', 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'block_size': 4096, u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'd3d1a749-9d6c-446a-8110-630dde06d04e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm4dpge', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8b2a1660-aea6-4cd5-8564-44f28282be7c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/blockdevices/12/', u'used_size': 107374182400, u'tags': [], u'uuid': u'435fab52-ed4c-4d91-bde2-36a9b7ddc9a7', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'm4dpge', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0f69e9c8-4f6d-4b24-8ecf-e1c6a84cef8e', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'm4dpge', 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'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'd3d1a749-9d6c-446a-8110-630dde06d04e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm4dpge', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8b2a1660-aea6-4cd5-8564-44f28282be7c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'm4dpge', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'm4dpge', 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'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'd3d1a749-9d6c-446a-8110-630dde06d04e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'm4dpge', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8b2a1660-aea6-4cd5-8564-44f28282be7c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'm4dpge', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'parents': [], u'system_id': u'm4dpge', u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/interfaces/18/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'system_id': u'm4dpge', u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'system_id': u'm4dpge', u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/interfaces/16/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'system_id': u'm4dpge', u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/interfaces/17/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'm4dpge', u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/m4dpge/blockdevices/12/', u'type': u'virtual', u'tags': [], u'uuid': u'435fab52-ed4c-4d91-bde2-36a9b7ddc9a7', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'm4dpge', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0f69e9c8-4f6d-4b24-8ecf-e1c6a84cef8e', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'partitions': []}], 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'resource_uri': u'/MAAS/api/2.0/machines/m4dpge/'}
2019-06-10 02:12:45,627 [salt.state       :300 ][INFO    ][6650] {'new': {'storage_layout': 'lvm'}}
2019-06-10 02:12:45,628 [salt.state       :1951][INFO    ][6650] Completed state [maas_machines_storage_cmp001_lvm] at time 02:12:45.628075 duration_in_ms=2395.554
2019-06-10 02:12:45,632 [salt.minion      :1711][INFO    ][6650] Returning information for job: 20190610021232524040
2019-06-10 02:12:46,341 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610021246329234
2019-06-10 02:12:46,372 [salt.minion      :1432][INFO    ][6710] Starting a new job with PID 6710
2019-06-10 02:12:47,420 [salt.state       :915 ][INFO    ][6710] Loading fresh modules for state activity
2019-06-10 02:12:47,477 [salt.fileclient  :1219][INFO    ][6710] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-10 02:12:47,519 [salt.state       :1780][INFO    ][6710] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:47.519372
2019-06-10 02:12:47,519 [salt.state       :1813][INFO    ][6710] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-10 02:12:47,521 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6710] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-10 02:12:49,506 [salt.state       :300 ][INFO    ][6710] {'pid': 6717, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-10 02:12:49,507 [salt.state       :1951][INFO    ][6710] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:49.507438 duration_in_ms=1988.065
2019-06-10 02:12:49,510 [salt.state       :1780][INFO    ][6710] Running state [maas.deploy_machines] at time 02:12:49.510529
2019-06-10 02:12:49,511 [salt.state       :1813][INFO    ][6710] Executing state module.run for [maas.deploy_machines]
2019-06-10 02:12:49,512 [salt.utils.decorators:613 ][WARNING ][6710] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:12:50,080 [salt.loaded.ext.module.maas:684 ][INFO    ][6710] deploymachines hwe_kernel=ga-16.04 system_id=p78wqg distro_series=xenial
2019-06-10 02:12:52,624 [salt.loaded.ext.module.maas:684 ][INFO    ][6710] deploymachines hwe_kernel=ga-16.04 system_id=m4dpge distro_series=xenial
2019-06-10 02:12:55,200 [salt.loaded.ext.module.maas:684 ][INFO    ][6710] deploymachines hwe_kernel=ga-16.04 system_id=mekswm distro_series=xenial
2019-06-10 02:12:57,763 [salt.loaded.ext.module.maas:684 ][INFO    ][6710] deploymachines hwe_kernel=ga-16.04 system_id=m78cfk distro_series=xenial
2019-06-10 02:13:00,300 [salt.loaded.ext.module.maas:684 ][INFO    ][6710] deploymachines hwe_kernel=ga-16.04 system_id=ftr3fb distro_series=xenial
2019-06-10 02:13:01,384 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021301371080
2019-06-10 02:13:01,405 [salt.minion      :1432][INFO    ][6976] Starting a new job with PID 6976
2019-06-10 02:13:01,439 [salt.minion      :1711][INFO    ][6976] Returning information for job: 20190610021301371080
2019-06-10 02:13:02,805 [salt.state       :300 ][INFO    ][6710] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-10 02:13:02,806 [salt.state       :1951][INFO    ][6710] Completed state [maas.deploy_machines] at time 02:13:02.806066 duration_in_ms=13295.536
2019-06-10 02:13:02,809 [salt.minion      :1711][INFO    ][6710] Returning information for job: 20190610021246329234
2019-06-10 02:13:03,537 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610021303519794
2019-06-10 02:13:03,562 [salt.minion      :1432][INFO    ][7005] Starting a new job with PID 7005
2019-06-10 02:13:09,739 [salt.state       :915 ][INFO    ][7005] Loading fresh modules for state activity
2019-06-10 02:13:09,801 [salt.fileclient  :1219][INFO    ][7005] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-10 02:13:09,858 [salt.state       :1780][INFO    ][7005] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:09.858205
2019-06-10 02:13:09,858 [salt.state       :1813][INFO    ][7005] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-10 02:13:09,860 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7005] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-10 02:13:11,839 [salt.state       :300 ][INFO    ][7005] {'pid': 7045, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-10 02:13:11,841 [salt.state       :1951][INFO    ][7005] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:13:11.841093 duration_in_ms=1982.887
2019-06-10 02:13:11,847 [salt.state       :1780][INFO    ][7005] Running state [maas.wait_for_machine_status] at time 02:13:11.847508
2019-06-10 02:13:11,847 [salt.state       :1813][INFO    ][7005] Executing state module.run for [maas.wait_for_machine_status]
2019-06-10 02:13:11,848 [salt.utils.decorators:613 ][WARNING ][7005] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:13:14,673 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.18715501s left)
2019-06-10 02:13:18,599 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021318581620
2019-06-10 02:13:18,628 [salt.minion      :1432][INFO    ][7060] Starting a new job with PID 7060
2019-06-10 02:13:18,657 [salt.minion      :1711][INFO    ][7060] Returning information for job: 20190610021318581620
2019-06-10 02:13:47,705 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.15462184s left)
2019-06-10 02:13:48,691 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021348676226
2019-06-10 02:13:48,713 [salt.minion      :1432][INFO    ][7086] Starting a new job with PID 7086
2019-06-10 02:13:48,739 [salt.minion      :1711][INFO    ][7086] Returning information for job: 20190610021348676226
2019-06-10 02:14:18,757 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021418747419
2019-06-10 02:14:18,777 [salt.minion      :1432][INFO    ][7134] Starting a new job with PID 7134
2019-06-10 02:14:18,811 [salt.minion      :1711][INFO    ][7134] Returning information for job: 20190610021418747419
2019-06-10 02:14:20,747 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.11277986s left)
2019-06-10 02:14:48,844 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021448826912
2019-06-10 02:14:48,875 [salt.minion      :1432][INFO    ][7156] Starting a new job with PID 7156
2019-06-10 02:14:48,904 [salt.minion      :1711][INFO    ][7156] Returning information for job: 20190610021448826912
2019-06-10 02:14:53,578 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.28166485s left)
2019-06-10 02:15:18,941 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021518921414
2019-06-10 02:15:18,967 [salt.minion      :1432][INFO    ][7227] Starting a new job with PID 7227
2019-06-10 02:15:18,995 [salt.minion      :1711][INFO    ][7227] Returning information for job: 20190610021518921414
2019-06-10 02:15:26,459 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.40088487s left)
2019-06-10 02:15:49,009 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021548996113
2019-06-10 02:15:49,040 [salt.minion      :1432][INFO    ][7304] Starting a new job with PID 7304
2019-06-10 02:15:49,073 [salt.minion      :1711][INFO    ][7304] Returning information for job: 20190610021548996113
2019-06-10 02:15:59,704 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.15545988s left)
2019-06-10 02:16:19,123 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021619114169
2019-06-10 02:16:19,150 [salt.minion      :1432][INFO    ][7466] Starting a new job with PID 7466
2019-06-10 02:16:19,177 [salt.minion      :1711][INFO    ][7466] Returning information for job: 20190610021619114169
2019-06-10 02:16:32,544 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.31578994s left)
2019-06-10 02:16:49,236 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021649220628
2019-06-10 02:16:49,265 [salt.minion      :1432][INFO    ][7547] Starting a new job with PID 7547
2019-06-10 02:16:49,300 [salt.minion      :1711][INFO    ][7547] Returning information for job: 20190610021649220628
2019-06-10 02:17:05,271 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.58846784s left)
2019-06-10 02:17:19,385 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021719368500
2019-06-10 02:17:19,415 [salt.minion      :1432][INFO    ][7818] Starting a new job with PID 7818
2019-06-10 02:17:19,447 [salt.minion      :1711][INFO    ][7818] Returning information for job: 20190610021719368500
2019-06-10 02:17:38,633 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.22707891s left)
2019-06-10 02:17:49,521 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021749508570
2019-06-10 02:17:49,547 [salt.minion      :1432][INFO    ][7984] Starting a new job with PID 7984
2019-06-10 02:17:49,583 [salt.minion      :1711][INFO    ][7984] Returning information for job: 20190610021749508570
2019-06-10 02:18:12,092 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.76715779s left)
2019-06-10 02:18:19,678 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021819657466
2019-06-10 02:18:19,710 [salt.minion      :1432][INFO    ][8312] Starting a new job with PID 8312
2019-06-10 02:18:19,740 [salt.minion      :1711][INFO    ][8312] Returning information for job: 20190610021819657466
2019-06-10 02:18:45,001 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.85861683s left)
2019-06-10 02:18:49,824 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021849815052
2019-06-10 02:18:49,852 [salt.minion      :1432][INFO    ][8458] Starting a new job with PID 8458
2019-06-10 02:18:49,881 [salt.minion      :1711][INFO    ][8458] Returning information for job: 20190610021849815052
2019-06-10 02:19:17,953 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.90665603s left)
2019-06-10 02:19:19,989 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021919978325
2019-06-10 02:19:20,011 [salt.minion      :1432][INFO    ][8714] Starting a new job with PID 8714
2019-06-10 02:19:20,038 [salt.minion      :1711][INFO    ][8714] Returning information for job: 20190610021919978325
2019-06-10 02:19:50,129 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610021950116410
2019-06-10 02:19:50,146 [salt.minion      :1432][INFO    ][8786] Starting a new job with PID 8786
2019-06-10 02:19:50,178 [salt.minion      :1711][INFO    ][8786] Returning information for job: 20190610021950116410
2019-06-10 02:19:51,151 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.70864201s left)
2019-06-10 02:20:20,334 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022020315318
2019-06-10 02:20:20,365 [salt.minion      :1432][INFO    ][9106] Starting a new job with PID 9106
2019-06-10 02:20:20,397 [salt.minion      :1711][INFO    ][9106] Returning information for job: 20190610022020315318
2019-06-10 02:20:24,000 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.85995889s left)
2019-06-10 02:20:50,487 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022050471812
2019-06-10 02:20:50,515 [salt.minion      :1432][INFO    ][9188] Starting a new job with PID 9188
2019-06-10 02:20:50,545 [salt.minion      :1711][INFO    ][9188] Returning information for job: 20190610022050471812
2019-06-10 02:20:56,887 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.97277784s left)
2019-06-10 02:21:20,661 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022120654485
2019-06-10 02:21:20,685 [salt.minion      :1432][INFO    ][9396] Starting a new job with PID 9396
2019-06-10 02:21:20,716 [salt.minion      :1711][INFO    ][9396] Returning information for job: 20190610022120654485
2019-06-10 02:21:29,647 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.21241879s left)
2019-06-10 02:21:50,816 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022150799126
2019-06-10 02:21:50,847 [salt.minion      :1432][INFO    ][9475] Starting a new job with PID 9475
2019-06-10 02:21:50,878 [salt.minion      :1711][INFO    ][9475] Returning information for job: 20190610022150799126
2019-06-10 02:22:02,432 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.42742395s left)
2019-06-10 02:22:21,002 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022220986240
2019-06-10 02:22:21,029 [salt.minion      :1432][INFO    ][9576] Starting a new job with PID 9576
2019-06-10 02:22:21,060 [salt.minion      :1711][INFO    ][9576] Returning information for job: 20190610022220986240
2019-06-10 02:22:35,416 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.44321585s left)
2019-06-10 02:22:51,189 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022251174512
2019-06-10 02:22:51,213 [salt.minion      :1432][INFO    ][9639] Starting a new job with PID 9639
2019-06-10 02:22:51,241 [salt.minion      :1711][INFO    ][9639] Returning information for job: 20190610022251174512
2019-06-10 02:23:09,316 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.54351497s left)
2019-06-10 02:23:21,210 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022321199145
2019-06-10 02:23:21,239 [salt.minion      :1432][INFO    ][9816] Starting a new job with PID 9816
2019-06-10 02:23:21,283 [salt.minion      :1711][INFO    ][9816] Returning information for job: 20190610022321199145
2019-06-10 02:23:42,048 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.8118329s left)
2019-06-10 02:23:51,404 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022351391202
2019-06-10 02:23:51,433 [salt.minion      :1432][INFO    ][9853] Starting a new job with PID 9853
2019-06-10 02:23:51,461 [salt.minion      :1711][INFO    ][9853] Returning information for job: 20190610022351391202
2019-06-10 02:24:14,891 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.96869302s left)
2019-06-10 02:24:21,620 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022421604548
2019-06-10 02:24:21,652 [salt.minion      :1432][INFO    ][9968] Starting a new job with PID 9968
2019-06-10 02:24:21,682 [salt.minion      :1711][INFO    ][9968] Returning information for job: 20190610022421604548
2019-06-10 02:24:47,695 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1554.16416693s left)
2019-06-10 02:24:51,811 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022451796136
2019-06-10 02:24:51,839 [salt.minion      :1432][INFO    ][9991] Starting a new job with PID 9991
2019-06-10 02:24:51,874 [salt.minion      :1711][INFO    ][9991] Returning information for job: 20190610022451796136
2019-06-10 02:25:20,640 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1521.21917081s left)
2019-06-10 02:25:21,835 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022521825709
2019-06-10 02:25:21,865 [salt.minion      :1432][INFO    ][10209] Starting a new job with PID 10209
2019-06-10 02:25:21,894 [salt.minion      :1711][INFO    ][10209] Returning information for job: 20190610022521825709
2019-06-10 02:25:51,993 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022551985625
2019-06-10 02:25:52,009 [salt.minion      :1432][INFO    ][10268] Starting a new job with PID 10268
2019-06-10 02:25:52,038 [salt.minion      :1711][INFO    ][10268] Returning information for job: 20190610022551985625
2019-06-10 02:25:53,563 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1488.2969799s left)
2019-06-10 02:26:22,064 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022622049141
2019-06-10 02:26:22,096 [salt.minion      :1432][INFO    ][10501] Starting a new job with PID 10501
2019-06-10 02:26:22,125 [salt.minion      :1711][INFO    ][10501] Returning information for job: 20190610022622049141
2019-06-10 02:26:26,523 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1455.33614302s left)
2019-06-10 02:26:52,098 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022652082238
2019-06-10 02:26:52,128 [salt.minion      :1432][INFO    ][10581] Starting a new job with PID 10581
2019-06-10 02:26:52,155 [salt.minion      :1711][INFO    ][10581] Returning information for job: 20190610022652082238
2019-06-10 02:26:59,312 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1422.54761195s left)
2019-06-10 02:27:22,137 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022722122393
2019-06-10 02:27:22,167 [salt.minion      :1432][INFO    ][10679] Starting a new job with PID 10679
2019-06-10 02:27:22,209 [salt.minion      :1711][INFO    ][10679] Returning information for job: 20190610022722122393
2019-06-10 02:27:32,119 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1389.74052596s left)
2019-06-10 02:27:52,217 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022752202800
2019-06-10 02:27:52,246 [salt.minion      :1432][INFO    ][10709] Starting a new job with PID 10709
2019-06-10 02:27:52,272 [salt.minion      :1711][INFO    ][10709] Returning information for job: 20190610022752202800
2019-06-10 02:28:04,971 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1356.88883996s left)
2019-06-10 02:28:22,281 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022822264197
2019-06-10 02:28:22,315 [salt.minion      :1432][INFO    ][10757] Starting a new job with PID 10757
2019-06-10 02:28:22,344 [salt.minion      :1711][INFO    ][10757] Returning information for job: 20190610022822264197
2019-06-10 02:28:37,894 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1323.96591091s left)
2019-06-10 02:28:52,368 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022852352936
2019-06-10 02:28:52,397 [salt.minion      :1432][INFO    ][10781] Starting a new job with PID 10781
2019-06-10 02:28:52,426 [salt.minion      :1711][INFO    ][10781] Returning information for job: 20190610022852352936
2019-06-10 02:29:10,722 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1291.13733101s left)
2019-06-10 02:29:22,468 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022922455115
2019-06-10 02:29:22,495 [salt.minion      :1432][INFO    ][10832] Starting a new job with PID 10832
2019-06-10 02:29:22,524 [salt.minion      :1711][INFO    ][10832] Returning information for job: 20190610022922455115
2019-06-10 02:29:43,630 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1258.22976398s left)
2019-06-10 02:29:52,584 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610022952568219
2019-06-10 02:29:52,613 [salt.minion      :1432][INFO    ][10853] Starting a new job with PID 10853
2019-06-10 02:29:52,645 [salt.minion      :1711][INFO    ][10853] Returning information for job: 20190610022952568219
2019-06-10 02:30:16,341 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1225.51861691s left)
2019-06-10 02:30:22,688 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023022669176
2019-06-10 02:30:22,720 [salt.minion      :1432][INFO    ][10910] Starting a new job with PID 10910
2019-06-10 02:30:22,748 [salt.minion      :1711][INFO    ][10910] Returning information for job: 20190610023022669176
2019-06-10 02:30:49,183 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1192.67628598s left)
2019-06-10 02:30:52,817 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023052806172
2019-06-10 02:30:52,842 [salt.minion      :1432][INFO    ][10934] Starting a new job with PID 10934
2019-06-10 02:30:52,875 [salt.minion      :1711][INFO    ][10934] Returning information for job: 20190610023052806172
2019-06-10 02:31:21,768 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1160.09148884s left)
2019-06-10 02:31:22,969 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023122955044
2019-06-10 02:31:22,989 [salt.minion      :1432][INFO    ][10986] Starting a new job with PID 10986
2019-06-10 02:31:23,021 [salt.minion      :1711][INFO    ][10986] Returning information for job: 20190610023122955044
2019-06-10 02:31:53,119 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023153108414
2019-06-10 02:31:53,140 [salt.minion      :1432][INFO    ][11015] Starting a new job with PID 11015
2019-06-10 02:31:53,184 [salt.minion      :1711][INFO    ][11015] Returning information for job: 20190610023153108414
2019-06-10 02:31:54,579 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1127.28077483s left)
2019-06-10 02:32:23,316 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023223305033
2019-06-10 02:32:23,344 [salt.minion      :1432][INFO    ][11063] Starting a new job with PID 11063
2019-06-10 02:32:23,379 [salt.minion      :1711][INFO    ][11063] Returning information for job: 20190610023223305033
2019-06-10 02:32:27,612 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1094.24768496s left)
2019-06-10 02:32:53,501 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023253488466
2019-06-10 02:32:53,525 [salt.minion      :1432][INFO    ][11097] Starting a new job with PID 11097
2019-06-10 02:32:53,556 [salt.minion      :1711][INFO    ][11097] Returning information for job: 20190610023253488466
2019-06-10 02:33:00,320 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1061.53975797s left)
2019-06-10 02:33:23,696 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023323683941
2019-06-10 02:33:23,722 [salt.minion      :1432][INFO    ][11139] Starting a new job with PID 11139
2019-06-10 02:33:23,754 [salt.minion      :1711][INFO    ][11139] Returning information for job: 20190610023323683941
2019-06-10 02:33:33,153 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1028.70684886s left)
2019-06-10 02:33:53,867 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023353858488
2019-06-10 02:33:53,891 [salt.minion      :1432][INFO    ][11173] Starting a new job with PID 11173
2019-06-10 02:33:53,923 [salt.minion      :1711][INFO    ][11173] Returning information for job: 20190610023353858488
2019-06-10 02:34:05,937 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (995.922871828s left)
2019-06-10 02:34:24,079 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023424056168
2019-06-10 02:34:24,107 [salt.minion      :1432][INFO    ][11221] Starting a new job with PID 11221
2019-06-10 02:34:24,139 [salt.minion      :1711][INFO    ][11221] Returning information for job: 20190610023424056168
2019-06-10 02:34:38,836 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (963.023955822s left)
2019-06-10 02:34:54,104 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023454093124
2019-06-10 02:34:54,130 [salt.minion      :1432][INFO    ][11257] Starting a new job with PID 11257
2019-06-10 02:34:54,160 [salt.minion      :1711][INFO    ][11257] Returning information for job: 20190610023454093124
2019-06-10 02:35:11,697 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (930.162472963s left)
2019-06-10 02:35:24,152 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023524139211
2019-06-10 02:35:24,180 [salt.minion      :1432][INFO    ][11318] Starting a new job with PID 11318
2019-06-10 02:35:24,215 [salt.minion      :1711][INFO    ][11318] Returning information for job: 20190610023524139211
2019-06-10 02:35:44,436 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (897.423702955s left)
2019-06-10 02:35:54,231 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023554216954
2019-06-10 02:35:54,256 [salt.minion      :1432][INFO    ][11354] Starting a new job with PID 11354
2019-06-10 02:35:54,287 [salt.minion      :1711][INFO    ][11354] Returning information for job: 20190610023554216954
2019-06-10 02:36:17,302 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (864.558019876s left)
2019-06-10 02:36:24,322 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023624313489
2019-06-10 02:36:24,352 [salt.minion      :1432][INFO    ][11400] Starting a new job with PID 11400
2019-06-10 02:36:24,383 [salt.minion      :1711][INFO    ][11400] Returning information for job: 20190610023624313489
2019-06-10 02:36:50,013 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (831.847118855s left)
2019-06-10 02:36:54,390 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023654377455
2019-06-10 02:36:54,420 [salt.minion      :1432][INFO    ][11438] Starting a new job with PID 11438
2019-06-10 02:36:54,451 [salt.minion      :1711][INFO    ][11438] Returning information for job: 20190610023654377455
2019-06-10 02:37:22,857 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (799.002537966s left)
2019-06-10 02:37:24,512 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023724500830
2019-06-10 02:37:24,536 [salt.minion      :1432][INFO    ][11479] Starting a new job with PID 11479
2019-06-10 02:37:24,570 [salt.minion      :1711][INFO    ][11479] Returning information for job: 20190610023724500830
2019-06-10 02:37:54,648 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023754639029
2019-06-10 02:37:54,667 [salt.minion      :1432][INFO    ][11511] Starting a new job with PID 11511
2019-06-10 02:37:54,697 [salt.minion      :1711][INFO    ][11511] Returning information for job: 20190610023754639029
2019-06-10 02:37:55,649 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (766.211088896s left)
2019-06-10 02:38:24,784 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023824768246
2019-06-10 02:38:24,809 [salt.minion      :1432][INFO    ][11551] Starting a new job with PID 11551
2019-06-10 02:38:24,838 [salt.minion      :1711][INFO    ][11551] Returning information for job: 20190610023824768246
2019-06-10 02:38:28,544 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (733.315212011s left)
2019-06-10 02:38:54,958 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023854944151
2019-06-10 02:38:54,984 [salt.minion      :1432][INFO    ][11583] Starting a new job with PID 11583
2019-06-10 02:38:55,015 [salt.minion      :1711][INFO    ][11583] Returning information for job: 20190610023854944151
2019-06-10 02:39:01,498 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (700.362488985s left)
2019-06-10 02:39:25,124 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023925113605
2019-06-10 02:39:25,152 [salt.minion      :1432][INFO    ][11629] Starting a new job with PID 11629
2019-06-10 02:39:25,186 [salt.minion      :1711][INFO    ][11629] Returning information for job: 20190610023925113605
2019-06-10 02:39:34,223 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (667.636477947s left)
2019-06-10 02:39:55,355 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610023955340118
2019-06-10 02:39:55,381 [salt.minion      :1432][INFO    ][11666] Starting a new job with PID 11666
2019-06-10 02:39:55,410 [salt.minion      :1711][INFO    ][11666] Returning information for job: 20190610023955340118
2019-06-10 02:40:06,948 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (634.911459923s left)
2019-06-10 02:40:25,544 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024025534604
2019-06-10 02:40:25,570 [salt.minion      :1432][INFO    ][11708] Starting a new job with PID 11708
2019-06-10 02:40:25,599 [salt.minion      :1711][INFO    ][11708] Returning information for job: 20190610024025534604
2019-06-10 02:40:39,975 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (601.884334803s left)
2019-06-10 02:40:55,594 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024055580861
2019-06-10 02:40:55,619 [salt.minion      :1432][INFO    ][11742] Starting a new job with PID 11742
2019-06-10 02:40:55,662 [salt.minion      :1711][INFO    ][11742] Returning information for job: 20190610024055580861
2019-06-10 02:41:12,631 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (569.229022026s left)
2019-06-10 02:41:25,626 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024125613001
2019-06-10 02:41:25,652 [salt.minion      :1432][INFO    ][11786] Starting a new job with PID 11786
2019-06-10 02:41:25,681 [salt.minion      :1711][INFO    ][11786] Returning information for job: 20190610024125613001
2019-06-10 02:41:45,328 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (536.531652927s left)
2019-06-10 02:41:55,721 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024155708461
2019-06-10 02:41:55,746 [salt.minion      :1432][INFO    ][11819] Starting a new job with PID 11819
2019-06-10 02:41:55,775 [salt.minion      :1711][INFO    ][11819] Returning information for job: 20190610024155708461
2019-06-10 02:42:18,042 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (503.81744194s left)
2019-06-10 02:42:25,824 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024225811936
2019-06-10 02:42:25,853 [salt.minion      :1432][INFO    ][11862] Starting a new job with PID 11862
2019-06-10 02:42:25,883 [salt.minion      :1711][INFO    ][11862] Returning information for job: 20190610024225811936
2019-06-10 02:42:51,024 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (470.835264921s left)
2019-06-10 02:42:55,961 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024255950729
2019-06-10 02:42:55,987 [salt.minion      :1432][INFO    ][11899] Starting a new job with PID 11899
2019-06-10 02:42:56,016 [salt.minion      :1711][INFO    ][11899] Returning information for job: 20190610024255950729
2019-06-10 02:43:23,976 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (437.883514881s left)
2019-06-10 02:43:26,131 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024326118989
2019-06-10 02:43:26,160 [salt.minion      :1432][INFO    ][11945] Starting a new job with PID 11945
2019-06-10 02:43:26,191 [salt.minion      :1711][INFO    ][11945] Returning information for job: 20190610024326118989
2019-06-10 02:43:56,257 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024356245363
2019-06-10 02:43:56,281 [salt.minion      :1432][INFO    ][11981] Starting a new job with PID 11981
2019-06-10 02:43:56,316 [salt.minion      :1711][INFO    ][11981] Returning information for job: 20190610024356245363
2019-06-10 02:43:56,742 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (405.118373871s left)
2019-06-10 02:44:26,489 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024426475243
2019-06-10 02:44:26,516 [salt.minion      :1432][INFO    ][12021] Starting a new job with PID 12021
2019-06-10 02:44:26,548 [salt.minion      :1711][INFO    ][12021] Returning information for job: 20190610024426475243
2019-06-10 02:44:29,727 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (372.132156849s left)
2019-06-10 02:44:56,698 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024456687271
2019-06-10 02:44:56,724 [salt.minion      :1432][INFO    ][12186] Starting a new job with PID 12186
2019-06-10 02:44:56,759 [salt.minion      :1711][INFO    ][12186] Returning information for job: 20190610024456687271
2019-06-10 02:45:02,499 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (339.360498905s left)
2019-06-10 02:45:26,773 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024526760159
2019-06-10 02:45:26,797 [salt.minion      :1432][INFO    ][12245] Starting a new job with PID 12245
2019-06-10 02:45:26,827 [salt.minion      :1711][INFO    ][12245] Returning information for job: 20190610024526760159
2019-06-10 02:45:35,214 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (306.646366835s left)
2019-06-10 02:45:57,004 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024556991001
2019-06-10 02:45:57,030 [salt.minion      :1432][INFO    ][12279] Starting a new job with PID 12279
2019-06-10 02:45:57,060 [salt.minion      :1711][INFO    ][12279] Returning information for job: 20190610024556991001
2019-06-10 02:46:08,383 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (273.477074862s left)
2019-06-10 02:46:27,079 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024627063875
2019-06-10 02:46:27,105 [salt.minion      :1432][INFO    ][12323] Starting a new job with PID 12323
2019-06-10 02:46:27,136 [salt.minion      :1711][INFO    ][12323] Returning information for job: 20190610024627063875
2019-06-10 02:46:41,153 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (240.706712961s left)
2019-06-10 02:46:57,116 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024657104353
2019-06-10 02:46:57,143 [salt.minion      :1432][INFO    ][12356] Starting a new job with PID 12356
2019-06-10 02:46:57,173 [salt.minion      :1711][INFO    ][12356] Returning information for job: 20190610024657104353
2019-06-10 02:47:13,928 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (207.931566s left)
2019-06-10 02:47:27,225 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024727210777
2019-06-10 02:47:27,255 [salt.minion      :1432][INFO    ][12406] Starting a new job with PID 12406
2019-06-10 02:47:27,288 [salt.minion      :1711][INFO    ][12406] Returning information for job: 20190610024727210777
2019-06-10 02:47:46,677 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (175.182164907s left)
2019-06-10 02:47:57,265 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024757258140
2019-06-10 02:47:57,287 [salt.minion      :1432][INFO    ][12441] Starting a new job with PID 12441
2019-06-10 02:47:57,317 [salt.minion      :1711][INFO    ][12441] Returning information for job: 20190610024757258140
2019-06-10 02:48:19,662 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (142.197644949s left)
2019-06-10 02:48:27,380 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024827365233
2019-06-10 02:48:27,406 [salt.minion      :1432][INFO    ][12480] Starting a new job with PID 12480
2019-06-10 02:48:27,437 [salt.minion      :1711][INFO    ][12480] Returning information for job: 20190610024827365233
2019-06-10 02:48:52,368 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (109.491117954s left)
2019-06-10 02:48:57,511 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024857501435
2019-06-10 02:48:57,540 [salt.minion      :1432][INFO    ][12515] Starting a new job with PID 12515
2019-06-10 02:48:57,567 [salt.minion      :1711][INFO    ][12515] Returning information for job: 20190610024857501435
2019-06-10 02:49:25,279 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (76.581099987s left)
2019-06-10 02:49:27,666 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024927656242
2019-06-10 02:49:27,690 [salt.minion      :1432][INFO    ][12560] Starting a new job with PID 12560
2019-06-10 02:49:27,720 [salt.minion      :1711][INFO    ][12560] Returning information for job: 20190610024927656242
2019-06-10 02:49:57,836 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610024957825555
2019-06-10 02:49:57,858 [salt.minion      :1432][INFO    ][12596] Starting a new job with PID 12596
2019-06-10 02:49:57,885 [salt.minion      :1711][INFO    ][12596] Returning information for job: 20190610024957825555
2019-06-10 02:49:58,099 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (43.760258913s left)
2019-06-10 02:50:27,984 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025027969421
2019-06-10 02:50:28,009 [salt.minion      :1432][INFO    ][12634] Starting a new job with PID 12634
2019-06-10 02:50:28,047 [salt.minion      :1711][INFO    ][12634] Returning information for job: 20190610025027969421
2019-06-10 02:50:30,860 [salt.loaded.ext.module.maas:1023][INFO    ][7005] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (10.999352932s left)
2019-06-10 02:50:58,015 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025057999672
2019-06-10 02:50:58,041 [salt.minion      :1432][INFO    ][12667] Starting a new job with PID 12667
2019-06-10 02:50:58,071 [salt.minion      :1711][INFO    ][12667] Returning information for job: 20190610025057999672
2019-06-10 02:51:03,630 [salt.state       :302 ][ERROR   ][7005] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-10 02:51:03,630 [salt.state       :1951][INFO    ][7005] Completed state [maas.wait_for_machine_status] at time 02:51:03.630692 duration_in_ms=2271783.181
2019-06-10 02:51:03,637 [salt.minion      :1711][INFO    ][7005] Returning information for job: 20190610021303519794
2019-06-10 02:51:14,572 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command pillar.get with jid 20190610025114560191
2019-06-10 02:51:14,601 [salt.minion      :1432][INFO    ][12707] Starting a new job with PID 12707
2019-06-10 02:51:14,612 [salt.minion      :1711][INFO    ][12707] Returning information for job: 20190610025114560191
2019-06-10 02:51:15,332 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command service.status with jid 20190610025115316997
2019-06-10 02:51:15,359 [salt.minion      :1432][INFO    ][12712] Starting a new job with PID 12712
2019-06-10 02:51:16,255 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12712] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-10 02:51:16,300 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12712] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-10 02:51:16,323 [salt.minion      :1711][INFO    ][12712] Returning information for job: 20190610025115316997
2019-06-10 02:51:17,043 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610025117031392
2019-06-10 02:51:17,072 [salt.minion      :1432][INFO    ][12723] Starting a new job with PID 12723
2019-06-10 02:51:23,036 [salt.state       :915 ][INFO    ][12723] Loading fresh modules for state activity
2019-06-10 02:51:23,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command 'salt-minion --version' in directory '/root'
2019-06-10 02:51:24,068 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command 'salt-minion --version' in directory '/root'
2019-06-10 02:51:25,160 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command 'salt-minion --version' in directory '/root'
2019-06-10 02:51:25,519 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command 'salt-minion --version' in directory '/root'
2019-06-10 02:51:27,787 [salt.state       :1780][INFO    ][12723] Running state [salt-minion] at time 02:51:27.787810
2019-06-10 02:51:27,788 [salt.state       :1813][INFO    ][12723] Executing state pkg.installed for [salt-minion]
2019-06-10 02:51:27,789 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-10 02:51:27,912 [salt.state       :300 ][INFO    ][12723] All specified packages are already installed
2019-06-10 02:51:27,913 [salt.state       :1951][INFO    ][12723] Completed state [salt-minion] at time 02:51:27.913371 duration_in_ms=125.561
2019-06-10 02:51:27,915 [salt.state       :1780][INFO    ][12723] Running state [salt_minion_dependency_packages] at time 02:51:27.915115
2019-06-10 02:51:27,915 [salt.state       :1813][INFO    ][12723] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-10 02:51:27,924 [salt.state       :300 ][INFO    ][12723] All specified packages are already installed
2019-06-10 02:51:27,925 [salt.state       :1951][INFO    ][12723] Completed state [salt_minion_dependency_packages] at time 02:51:27.924969 duration_in_ms=9.854
2019-06-10 02:51:27,932 [salt.state       :1780][INFO    ][12723] Running state [/etc/salt/minion.d/minion.conf] at time 02:51:27.932347
2019-06-10 02:51:27,932 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-10 02:51:28,220 [salt.state       :300 ][INFO    ][12723] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-10 02:51:28,221 [salt.state       :1951][INFO    ][12723] Completed state [/etc/salt/minion.d/minion.conf] at time 02:51:28.221219 duration_in_ms=288.871
2019-06-10 02:51:28,221 [salt.state       :1780][INFO    ][12723] Running state [python-netaddr] at time 02:51:28.221528
2019-06-10 02:51:28,222 [salt.state       :1813][INFO    ][12723] Executing state pkg.installed for [python-netaddr]
2019-06-10 02:51:28,231 [salt.state       :300 ][INFO    ][12723] All specified packages are already installed
2019-06-10 02:51:28,231 [salt.state       :1951][INFO    ][12723] Completed state [python-netaddr] at time 02:51:28.231912 duration_in_ms=10.385
2019-06-10 02:51:28,235 [salt.state       :1780][INFO    ][12723] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:28.235593
2019-06-10 02:51:28,235 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-10 02:51:28,253 [salt.state       :300 ][INFO    ][12723] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-10 02:51:28,254 [salt.state       :1951][INFO    ][12723] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:51:28.254850 duration_in_ms=19.257
2019-06-10 02:51:28,256 [salt.state       :1780][INFO    ][12723] Running state [salt-minion] at time 02:51:28.256443
2019-06-10 02:51:28,256 [salt.state       :1813][INFO    ][12723] Executing state service.running for [salt-minion]
2019-06-10 02:51:28,258 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-10 02:51:28,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-10 02:51:28,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-10 02:51:28,353 [salt.state       :300 ][INFO    ][12723] The service salt-minion is already running
2019-06-10 02:51:28,354 [salt.state       :1951][INFO    ][12723] Completed state [salt-minion] at time 02:51:28.354521 duration_in_ms=98.077
2019-06-10 02:51:28,359 [salt.state       :1780][INFO    ][12723] Running state [/etc/salt/grains.d] at time 02:51:28.357695
2019-06-10 02:51:28,359 [salt.state       :1813][INFO    ][12723] Executing state file.directory for [/etc/salt/grains.d]
2019-06-10 02:51:28,362 [salt.state       :300 ][INFO    ][12723] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-10 02:51:28,362 [salt.state       :1951][INFO    ][12723] Completed state [/etc/salt/grains.d] at time 02:51:28.362643 duration_in_ms=4.948
2019-06-10 02:51:28,363 [salt.state       :1780][INFO    ][12723] Running state [/etc/salt/grains] at time 02:51:28.363445
2019-06-10 02:51:28,363 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/salt/grains]
2019-06-10 02:51:28,365 [salt.state       :300 ][INFO    ][12723] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-10 02:51:28,365 [salt.state       :1951][INFO    ][12723] Completed state [/etc/salt/grains] at time 02:51:28.365328 duration_in_ms=1.883
2019-06-10 02:51:28,365 [salt.state       :1780][INFO    ][12723] Running state [/etc/salt/grains.d/placeholder] at time 02:51:28.365928
2019-06-10 02:51:28,366 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-10 02:51:28,366 [salt.state       :300 ][INFO    ][12723] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-10 02:51:28,367 [salt.state       :1951][INFO    ][12723] Completed state [/etc/salt/grains.d/placeholder] at time 02:51:28.367187 duration_in_ms=1.259
2019-06-10 02:51:28,367 [salt.state       :1780][INFO    ][12723] Running state [/etc/salt/grains.d/sphinx] at time 02:51:28.367776
2019-06-10 02:51:28,368 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-10 02:51:28,369 [salt.state       :300 ][INFO    ][12723] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-10 02:51:28,371 [salt.state       :1951][INFO    ][12723] Completed state [/etc/salt/grains.d/sphinx] at time 02:51:28.371355 duration_in_ms=3.579
2019-06-10 02:51:28,374 [salt.state       :1780][INFO    ][12723] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.374225
2019-06-10 02:51:28,374 [salt.state       :1813][INFO    ][12723] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-10 02:51:28,374 [salt.state       :300 ][INFO    ][12723] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-10 02:51:28,375 [salt.state       :1951][INFO    ][12723] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.375072 duration_in_ms=0.847
2019-06-10 02:51:28,375 [salt.state       :1780][INFO    ][12723] Running state [/etc/salt/grains.d/dns_records] at time 02:51:28.375587
2019-06-10 02:51:28,375 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-10 02:51:28,376 [salt.state       :300 ][INFO    ][12723] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-10 02:51:28,377 [salt.state       :1951][INFO    ][12723] Completed state [/etc/salt/grains.d/dns_records] at time 02:51:28.377164 duration_in_ms=1.577
2019-06-10 02:51:28,378 [salt.state       :1780][INFO    ][12723] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.378134
2019-06-10 02:51:28,378 [salt.state       :1813][INFO    ][12723] 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-10 02:51:28,378 [salt.state       :300 ][INFO    ][12723] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-10 02:51:28,379 [salt.state       :1951][INFO    ][12723] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.378983 duration_in_ms=0.849
2019-06-10 02:51:28,379 [salt.state       :1780][INFO    ][12723] Running state [/etc/salt/grains.d/salt] at time 02:51:28.379492
2019-06-10 02:51:28,379 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-10 02:51:28,380 [salt.state       :300 ][INFO    ][12723] File /etc/salt/grains.d/salt is in the correct state
2019-06-10 02:51:28,381 [salt.state       :1951][INFO    ][12723] Completed state [/etc/salt/grains.d/salt] at time 02:51:28.381072 duration_in_ms=1.579
2019-06-10 02:51:28,382 [salt.state       :1780][INFO    ][12723] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.382416
2019-06-10 02:51:28,382 [salt.state       :1813][INFO    ][12723] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-10 02:51:28,383 [salt.state       :300 ][INFO    ][12723] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-10 02:51:28,383 [salt.state       :1951][INFO    ][12723] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:51:28.383237 duration_in_ms=0.822
2019-06-10 02:51:28,385 [salt.state       :1780][INFO    ][12723] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:28.385173
2019-06-10 02:51:28,385 [salt.state       :1813][INFO    ][12723] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-10 02:51:28,386 [salt.state       :300 ][INFO    ][12723] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-10 02:51:28,386 [salt.state       :1951][INFO    ][12723] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:51:28.386399 duration_in_ms=1.227
2019-06-10 02:51:28,387 [salt.state       :1780][INFO    ][12723] Running state [mine.update] at time 02:51:28.387094
2019-06-10 02:51:28,387 [salt.state       :1813][INFO    ][12723] Executing state module.wait for [mine.update]
2019-06-10 02:51:28,387 [salt.state       :300 ][INFO    ][12723] No changes made for mine.update
2019-06-10 02:51:28,387 [salt.state       :1951][INFO    ][12723] Completed state [mine.update] at time 02:51:28.387859 duration_in_ms=0.765
2019-06-10 02:51:28,388 [salt.state       :1780][INFO    ][12723] Running state [ca-certificates] at time 02:51:28.388130
2019-06-10 02:51:28,388 [salt.state       :1813][INFO    ][12723] Executing state pkg.installed for [ca-certificates]
2019-06-10 02:51:28,398 [salt.state       :300 ][INFO    ][12723] All specified packages are already installed
2019-06-10 02:51:28,399 [salt.state       :1951][INFO    ][12723] Completed state [ca-certificates] at time 02:51:28.399020 duration_in_ms=10.889
2019-06-10 02:51:28,399 [salt.state       :1780][INFO    ][12723] Running state [update-ca-certificates] at time 02:51:28.399753
2019-06-10 02:51:28,400 [salt.state       :1813][INFO    ][12723] Executing state cmd.wait for [update-ca-certificates]
2019-06-10 02:51:28,400 [salt.state       :300 ][INFO    ][12723] No changes made for update-ca-certificates
2019-06-10 02:51:28,400 [salt.state       :1951][INFO    ][12723] Completed state [update-ca-certificates] at time 02:51:28.400574 duration_in_ms=0.821
2019-06-10 02:51:28,400 [salt.state       :1780][INFO    ][12723] Running state [iptables] at time 02:51:28.400845
2019-06-10 02:51:28,401 [salt.state       :1813][INFO    ][12723] Executing state pkg.installed for [iptables]
2019-06-10 02:51:28,411 [salt.state       :300 ][INFO    ][12723] All specified packages are already installed
2019-06-10 02:51:28,411 [salt.state       :1951][INFO    ][12723] Completed state [iptables] at time 02:51:28.411666 duration_in_ms=10.822
2019-06-10 02:51:28,411 [salt.state       :1780][INFO    ][12723] Running state [iptables-persistent] at time 02:51:28.411942
2019-06-10 02:51:28,412 [salt.state       :1813][INFO    ][12723] Executing state pkg.installed for [iptables-persistent]
2019-06-10 02:51:28,421 [salt.state       :300 ][INFO    ][12723] All specified packages are already installed
2019-06-10 02:51:28,422 [salt.state       :1951][INFO    ][12723] Completed state [iptables-persistent] at time 02:51:28.422039 duration_in_ms=10.097
2019-06-10 02:51:28,423 [salt.state       :1780][INFO    ][12723] Running state [iptables_modules_v4_load] at time 02:51:28.423072
2019-06-10 02:51:28,423 [salt.state       :1813][INFO    ][12723] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-10 02:51:28,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command 'lsmod' in directory '/root'
2019-06-10 02:51:28,447 [salt.state       :300 ][INFO    ][12723] Kernel modules iptable_filter, ip_tables are already present
2019-06-10 02:51:28,448 [salt.state       :1951][INFO    ][12723] Completed state [iptables_modules_v4_load] at time 02:51:28.448463 duration_in_ms=25.39
2019-06-10 02:51:28,450 [salt.state       :1780][INFO    ][12723] Running state [/etc/iptables/rules.v4] at time 02:51:28.450125
2019-06-10 02:51:28,450 [salt.state       :1813][INFO    ][12723] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-10 02:51:28,561 [salt.state       :300 ][INFO    ][12723] File /etc/iptables/rules.v4 is in the correct state
2019-06-10 02:51:28,562 [salt.state       :1951][INFO    ][12723] Completed state [/etc/iptables/rules.v4] at time 02:51:28.562195 duration_in_ms=112.07
2019-06-10 02:51:28,563 [salt.state       :1780][INFO    ][12723] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:28.563332
2019-06-10 02:51:28,563 [salt.state       :1813][INFO    ][12723] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-10 02:51:28,564 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-10 02:51:28,584 [salt.state       :300 ][INFO    ][12723] onlyif execution failed
2019-06-10 02:51:28,584 [salt.state       :1951][INFO    ][12723] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:51:28.584816 duration_in_ms=21.484
2019-06-10 02:51:28,589 [salt.state       :1780][INFO    ][12723] Running state [netfilter-persistent] at time 02:51:28.588965
2019-06-10 02:51:28,590 [salt.state       :1813][INFO    ][12723] Executing state service.running for [netfilter-persistent]
2019-06-10 02:51:28,591 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-10 02:51:28,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-10 02:51:28,641 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-10 02:51:28,662 [salt.state       :300 ][INFO    ][12723] The service netfilter-persistent is already running
2019-06-10 02:51:28,663 [salt.state       :1951][INFO    ][12723] Completed state [netfilter-persistent] at time 02:51:28.663087 duration_in_ms=74.122
2019-06-10 02:51:28,664 [salt.state       :1780][INFO    ][12723] Running state [iptables_extra.remove_stale_tables] at time 02:51:28.664647
2019-06-10 02:51:28,665 [salt.state       :1813][INFO    ][12723] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-10 02:51:28,665 [salt.state       :300 ][INFO    ][12723] No changes made for iptables_extra.remove_stale_tables
2019-06-10 02:51:28,666 [salt.state       :1951][INFO    ][12723] Completed state [iptables_extra.remove_stale_tables] at time 02:51:28.666030 duration_in_ms=1.384
2019-06-10 02:51:28,666 [salt.state       :1780][INFO    ][12723] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:28.666502
2019-06-10 02:51:28,667 [salt.state       :1813][INFO    ][12723] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-10 02:51:28,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12723] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-10 02:51:28,687 [salt.state       :300 ][INFO    ][12723] onlyif execution failed
2019-06-10 02:51:28,688 [salt.state       :1951][INFO    ][12723] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:51:28.688322 duration_in_ms=21.819
2019-06-10 02:51:28,690 [salt.state       :1780][INFO    ][12723] Running state [/etc/iptables/rules.v6] at time 02:51:28.690284
2019-06-10 02:51:28,691 [salt.state       :1813][INFO    ][12723] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-10 02:51:28,691 [salt.state       :300 ][INFO    ][12723] File /etc/iptables/rules.v6 is not present
2019-06-10 02:51:28,692 [salt.state       :1951][INFO    ][12723] Completed state [/etc/iptables/rules.v6] at time 02:51:28.692326 duration_in_ms=2.043
2019-06-10 02:51:28,695 [salt.state       :1780][INFO    ][12723] Running state [iptables_extra.flush_all] at time 02:51:28.694641
2019-06-10 02:51:28,695 [salt.state       :1813][INFO    ][12723] Executing state module.wait for [iptables_extra.flush_all]
2019-06-10 02:51:28,696 [salt.state       :300 ][INFO    ][12723] No changes made for iptables_extra.flush_all
2019-06-10 02:51:28,696 [salt.state       :1951][INFO    ][12723] Completed state [iptables_extra.flush_all] at time 02:51:28.696718 duration_in_ms=2.077
2019-06-10 02:51:28,702 [salt.minion      :1711][INFO    ][12723] Returning information for job: 20190610025117031392
2019-06-10 02:51:29,419 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610025129406205
2019-06-10 02:51:29,443 [salt.minion      :1432][INFO    ][12803] Starting a new job with PID 12803
2019-06-10 02:51:30,454 [salt.state       :915 ][INFO    ][12803] Loading fresh modules for state activity
2019-06-10 02:51:31,763 [salt.state       :1780][INFO    ][12803] Running state [maas-rack-controller] at time 02:51:31.763352
2019-06-10 02:51:31,763 [salt.state       :1813][INFO    ][12803] Executing state pkg.installed for [maas-rack-controller]
2019-06-10 02:51:31,764 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12803] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-10 02:51:31,902 [salt.state       :300 ][INFO    ][12803] All specified packages are already installed
2019-06-10 02:51:31,903 [salt.state       :1951][INFO    ][12803] Completed state [maas-rack-controller] at time 02:51:31.903207 duration_in_ms=139.855
2019-06-10 02:51:31,903 [salt.state       :1780][INFO    ][12803] Running state [ipmitool] at time 02:51:31.903575
2019-06-10 02:51:31,903 [salt.state       :1813][INFO    ][12803] Executing state pkg.installed for [ipmitool]
2019-06-10 02:51:31,918 [salt.state       :300 ][INFO    ][12803] All specified packages are already installed
2019-06-10 02:51:31,919 [salt.state       :1951][INFO    ][12803] Completed state [ipmitool] at time 02:51:31.919045 duration_in_ms=15.469
2019-06-10 02:51:31,924 [salt.state       :1780][INFO    ][12803] Running state [/etc/maas/rackd.conf] at time 02:51:31.924810
2019-06-10 02:51:31,925 [salt.state       :1813][INFO    ][12803] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-10 02:51:31,926 [salt.state       :300 ][INFO    ][12803] No changes needed to be made
2019-06-10 02:51:31,926 [salt.state       :1951][INFO    ][12803] Completed state [/etc/maas/rackd.conf] at time 02:51:31.926693 duration_in_ms=1.884
2019-06-10 02:51:31,927 [salt.state       :1780][INFO    ][12803] Running state [/etc/maas/rackd.conf] at time 02:51:31.926975
2019-06-10 02:51:31,927 [salt.state       :1813][INFO    ][12803] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-10 02:51:31,927 [salt.loaded.int.states.file:2298][WARNING ][12803] 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-10 02:51:31,928 [salt.state       :300 ][INFO    ][12803] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-10 02:51:31,928 [salt.state       :1951][INFO    ][12803] Completed state [/etc/maas/rackd.conf] at time 02:51:31.928666 duration_in_ms=1.691
2019-06-10 02:51:31,929 [salt.state       :1780][INFO    ][12803] Running state [maas-rackd] at time 02:51:31.929752
2019-06-10 02:51:31,930 [salt.state       :1813][INFO    ][12803] Executing state service.running for [maas-rackd]
2019-06-10 02:51:31,930 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12803] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-10 02:51:31,973 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12803] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-10 02:51:31,992 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12803] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-10 02:51:32,014 [salt.state       :300 ][INFO    ][12803] The service maas-rackd is already running
2019-06-10 02:51:32,015 [salt.state       :1951][INFO    ][12803] Completed state [maas-rackd] at time 02:51:32.015171 duration_in_ms=85.418
2019-06-10 02:51:32,017 [salt.minion      :1711][INFO    ][12803] Returning information for job: 20190610025129406205
2019-06-10 02:51:32,743 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610025132734841
2019-06-10 02:51:32,770 [salt.minion      :1432][INFO    ][12828] Starting a new job with PID 12828
2019-06-10 02:51:33,753 [salt.state       :915 ][INFO    ][12828] Loading fresh modules for state activity
2019-06-10 02:51:35,185 [salt.state       :1780][INFO    ][12828] Running state [maas-region-controller] at time 02:51:35.185516
2019-06-10 02:51:35,187 [salt.state       :1813][INFO    ][12828] Executing state pkg.installed for [maas-region-controller]
2019-06-10 02:51:35,189 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-10 02:51:35,310 [salt.state       :300 ][INFO    ][12828] All specified packages are already installed
2019-06-10 02:51:35,310 [salt.state       :1951][INFO    ][12828] Completed state [maas-region-controller] at time 02:51:35.310658 duration_in_ms=125.144
2019-06-10 02:51:35,311 [salt.state       :1780][INFO    ][12828] Running state [python-oauth] at time 02:51:35.311022
2019-06-10 02:51:35,311 [salt.state       :1813][INFO    ][12828] Executing state pkg.installed for [python-oauth]
2019-06-10 02:51:35,320 [salt.state       :300 ][INFO    ][12828] All specified packages are already installed
2019-06-10 02:51:35,320 [salt.state       :1951][INFO    ][12828] Completed state [python-oauth] at time 02:51:35.320922 duration_in_ms=9.9
2019-06-10 02:51:35,324 [salt.state       :1780][INFO    ][12828] Running state [/etc/maas/regiond.conf] at time 02:51:35.323958
2019-06-10 02:51:35,324 [salt.state       :1813][INFO    ][12828] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-10 02:51:35,329 [salt.state       :300 ][INFO    ][12828] No changes needed to be made
2019-06-10 02:51:35,329 [salt.state       :1951][INFO    ][12828] Completed state [/etc/maas/regiond.conf] at time 02:51:35.329405 duration_in_ms=5.446
2019-06-10 02:51:35,330 [salt.state       :1780][INFO    ][12828] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:35.330456
2019-06-10 02:51:35,330 [salt.state       :1813][INFO    ][12828] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-10 02:51:35,396 [salt.state       :300 ][INFO    ][12828] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-10 02:51:35,397 [salt.state       :1951][INFO    ][12828] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:51:35.397093 duration_in_ms=66.637
2019-06-10 02:51:35,397 [salt.state       :1780][INFO    ][12828] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:35.397617
2019-06-10 02:51:35,398 [salt.state       :1813][INFO    ][12828] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-10 02:51:35,403 [salt.state       :300 ][INFO    ][12828] No changes needed to be made
2019-06-10 02:51:35,403 [salt.state       :1951][INFO    ][12828] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:35.403213 duration_in_ms=5.596
2019-06-10 02:51:35,403 [salt.state       :1780][INFO    ][12828] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:35.403724
2019-06-10 02:51:35,404 [salt.state       :1813][INFO    ][12828] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-10 02:51:35,408 [salt.state       :300 ][INFO    ][12828] No changes needed to be made
2019-06-10 02:51:35,408 [salt.state       :1951][INFO    ][12828] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:51:35.408457 duration_in_ms=4.732
2019-06-10 02:51:35,409 [salt.state       :1780][INFO    ][12828] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:35.408969
2019-06-10 02:51:35,409 [salt.state       :1813][INFO    ][12828] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-10 02:51:35,444 [salt.state       :300 ][INFO    ][12828] No changes needed to be made
2019-06-10 02:51:35,444 [salt.state       :1951][INFO    ][12828] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:51:35.444598 duration_in_ms=35.628
2019-06-10 02:51:35,445 [salt.state       :1780][INFO    ][12828] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:35.445184
2019-06-10 02:51:35,445 [salt.state       :1813][INFO    ][12828] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-10 02:51:35,465 [salt.state       :300 ][INFO    ][12828] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-10 02:51:35,466 [salt.state       :1951][INFO    ][12828] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:51:35.465732 duration_in_ms=20.547
2019-06-10 02:51:35,467 [salt.state       :1780][INFO    ][12828] Running state [a2enmod headers] at time 02:51:35.467559
2019-06-10 02:51:35,468 [salt.state       :1813][INFO    ][12828] Executing state cmd.run for [a2enmod headers]
2019-06-10 02:51:35,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command 'a2enmod headers' in directory '/root'
2019-06-10 02:51:35,541 [salt.state       :300 ][INFO    ][12828] {'pid': 12848, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-10 02:51:35,542 [salt.state       :1951][INFO    ][12828] Completed state [a2enmod headers] at time 02:51:35.542074 duration_in_ms=74.515
2019-06-10 02:51:35,542 [salt.state       :1780][INFO    ][12828] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:35.542614
2019-06-10 02:51:35,543 [salt.state       :1813][INFO    ][12828] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-10 02:51:35,567 [salt.state       :300 ][INFO    ][12828] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-10 02:51:35,567 [salt.state       :1951][INFO    ][12828] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:51:35.567627 duration_in_ms=25.013
2019-06-10 02:51:35,568 [salt.state       :1780][INFO    ][12828] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:35.568422
2019-06-10 02:51:35,568 [salt.state       :1813][INFO    ][12828] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-10 02:51:35,637 [salt.state       :300 ][INFO    ][12828] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-10 02:51:35,637 [salt.state       :1951][INFO    ][12828] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:51:35.637372 duration_in_ms=68.95
2019-06-10 02:51:35,637 [salt.state       :1780][INFO    ][12828] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:35.637914
2019-06-10 02:51:35,638 [salt.state       :1813][INFO    ][12828] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-10 02:51:35,710 [salt.state       :300 ][INFO    ][12828] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-10 02:51:35,711 [salt.state       :1951][INFO    ][12828] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:51:35.711127 duration_in_ms=73.213
2019-06-10 02:51:35,711 [salt.state       :1780][INFO    ][12828] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:35.711693
2019-06-10 02:51:35,712 [salt.state       :1813][INFO    ][12828] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-10 02:51:35,795 [salt.state       :300 ][INFO    ][12828] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-10 02:51:35,795 [salt.state       :1951][INFO    ][12828] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:51:35.795402 duration_in_ms=83.709
2019-06-10 02:51:35,795 [salt.state       :1780][INFO    ][12828] Running state [/root/.pgpass] at time 02:51:35.795696
2019-06-10 02:51:35,796 [salt.state       :1813][INFO    ][12828] Executing state file.managed for [/root/.pgpass]
2019-06-10 02:51:35,850 [salt.state       :300 ][INFO    ][12828] File /root/.pgpass is in the correct state
2019-06-10 02:51:35,851 [salt.state       :1951][INFO    ][12828] Completed state [/root/.pgpass] at time 02:51:35.851122 duration_in_ms=55.426
2019-06-10 02:51:35,859 [salt.state       :1780][INFO    ][12828] Running state [maas-region syncdb --noinput] at time 02:51:35.859185
2019-06-10 02:51:35,859 [salt.state       :1813][INFO    ][12828] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-10 02:51:35,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-10 02:51:38,571 [salt.state       :300 ][INFO    ][12828] {'pid': 12864, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sessions, maasserver, contenttypes, sites, piston3, metadataserver, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-10 02:51:38,572 [salt.state       :1951][INFO    ][12828] Completed state [maas-region syncdb --noinput] at time 02:51:38.572603 duration_in_ms=2713.417
2019-06-10 02:51:38,573 [salt.state       :2022][WARNING ][12828] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-10 02:51:38,577 [salt.state       :1780][INFO    ][12828] Running state [maas-regiond] at time 02:51:38.577404
2019-06-10 02:51:38,578 [salt.state       :1813][INFO    ][12828] Executing state service.running for [maas-regiond]
2019-06-10 02:51:38,580 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-10 02:51:38,626 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-10 02:51:38,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-10 02:51:38,672 [salt.state       :300 ][INFO    ][12828] The service maas-regiond is already running
2019-06-10 02:51:38,672 [salt.state       :1951][INFO    ][12828] Completed state [maas-regiond] at time 02:51:38.672813 duration_in_ms=95.409
2019-06-10 02:51:38,676 [salt.state       :1780][INFO    ][12828] Running state [bind9] at time 02:51:38.676278
2019-06-10 02:51:38,676 [salt.state       :1813][INFO    ][12828] Executing state service.running for [bind9]
2019-06-10 02:51:38,679 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-10 02:51:38,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-10 02:51:38,723 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-10 02:51:38,743 [salt.state       :300 ][INFO    ][12828] The service bind9 is already running
2019-06-10 02:51:38,744 [salt.state       :1951][INFO    ][12828] Completed state [bind9] at time 02:51:38.743905 duration_in_ms=67.627
2019-06-10 02:51:38,747 [salt.state       :1780][INFO    ][12828] Running state [apache2] at time 02:51:38.747414
2019-06-10 02:51:38,748 [salt.state       :1813][INFO    ][12828] Executing state service.running for [apache2]
2019-06-10 02:51:38,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-10 02:51:38,773 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-10 02:51:38,796 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-10 02:51:38,828 [salt.state       :300 ][INFO    ][12828] The service apache2 is already running
2019-06-10 02:51:38,829 [salt.state       :1951][INFO    ][12828] Completed state [apache2] at time 02:51:38.829266 duration_in_ms=81.849
2019-06-10 02:51:38,832 [salt.state       :1780][INFO    ][12828] Running state [maasng.wait_for_http_code] at time 02:51:38.832110
2019-06-10 02:51:38,832 [salt.state       :1813][INFO    ][12828] Executing state module.run for [maasng.wait_for_http_code]
2019-06-10 02:51:38,833 [salt.utils.decorators:613 ][WARNING ][12828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:51:38,991 [salt.state       :300 ][INFO    ][12828] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-10 02:51:38,991 [salt.state       :1951][INFO    ][12828] Completed state [maasng.wait_for_http_code] at time 02:51:38.991863 duration_in_ms=159.753
2019-06-10 02:51:38,993 [salt.state       :1780][INFO    ][12828] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:38.993057
2019-06-10 02:51:38,993 [salt.state       :1813][INFO    ][12828] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-10 02:51:38,994 [salt.state       :300 ][INFO    ][12828] /var/lib/maas/.setup_admin exists
2019-06-10 02:51:38,994 [salt.state       :1951][INFO    ][12828] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:51:38.994816 duration_in_ms=1.759
2019-06-10 02:51:38,995 [salt.state       :1780][INFO    ][12828] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:38.995845
2019-06-10 02:51:38,996 [salt.state       :1813][INFO    ][12828] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-10 02:51:38,997 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12828] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-10 02:51:40,840 [salt.state       :300 ][INFO    ][12828] {'pid': 12890, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-10 02:51:40,841 [salt.state       :1951][INFO    ][12828] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:51:40.841423 duration_in_ms=1845.578
2019-06-10 02:51:40,852 [salt.state       :1780][INFO    ][12828] Running state [maas_region_boot_source_resources_mirror] at time 02:51:40.852544
2019-06-10 02:51:40,852 [salt.state       :1813][INFO    ][12828] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-10 02:51:40,942 [salt.state       :300 ][INFO    ][12828] {'changes': {}}
2019-06-10 02:51:40,943 [salt.state       :1951][INFO    ][12828] Completed state [maas_region_boot_source_resources_mirror] at time 02:51:40.943146 duration_in_ms=90.601
2019-06-10 02:51:40,944 [salt.state       :1780][INFO    ][12828] Running state [maasng.boot_resources_import] at time 02:51:40.944315
2019-06-10 02:51:40,944 [salt.state       :1813][INFO    ][12828] Executing state module.run for [maasng.boot_resources_import]
2019-06-10 02:51:40,945 [salt.utils.decorators:613 ][WARNING ][12828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:51:42,370 [salt.loaded.ext.module.maasng:1600][INFO    ][12828] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2019-06-10 02:51:47,435 [salt.loaded.ext.module.maasng:1600][INFO    ][12828] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-06-10 02:51:47,804 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025147794296
2019-06-10 02:51:47,831 [salt.minion      :1432][INFO    ][12918] Starting a new job with PID 12918
2019-06-10 02:51:47,864 [salt.minion      :1711][INFO    ][12918] Returning information for job: 20190610025147794296
2019-06-10 02:51:52,539 [salt.state       :300 ][INFO    ][12828] {'ret': True}
2019-06-10 02:51:52,539 [salt.state       :1951][INFO    ][12828] Completed state [maasng.boot_resources_import] at time 02:51:52.539886 duration_in_ms=11595.57
2019-06-10 02:51:52,540 [salt.state       :1780][INFO    ][12828] Running state [maas_region_boot_sources_selection_xenial] at time 02:51:52.540894
2019-06-10 02:51:52,541 [salt.state       :1813][INFO    ][12828] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-10 02:51:52,716 [salt.state       :300 ][INFO    ][12828] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-10 02:51:52,717 [salt.state       :1951][INFO    ][12828] Completed state [maas_region_boot_sources_selection_xenial] at time 02:51:52.717106 duration_in_ms=176.212
2019-06-10 02:51:52,719 [salt.state       :1780][INFO    ][12828] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:51:52.719034
2019-06-10 02:51:52,719 [salt.state       :1813][INFO    ][12828] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-10 02:51:52,720 [salt.utils.decorators:613 ][WARNING ][12828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:51:52,720 [salt.loaded.ext.module.maasng:1771][INFO    ][12828] boot-sources sync initiated for ALL Rack's
2019-06-10 02:51:53,854 [salt.state       :300 ][INFO    ][12828] {'ret': True}
2019-06-10 02:51:53,854 [salt.state       :1951][INFO    ][12828] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:51:53.854431 duration_in_ms=1135.396
2019-06-10 02:51:53,856 [salt.state       :1780][INFO    ][12828] Running state [maas.process_maas_config] at time 02:51:53.856457
2019-06-10 02:51:53,857 [salt.state       :1813][INFO    ][12828] Executing state module.run for [maas.process_maas_config]
2019-06-10 02:51:53,859 [salt.utils.decorators:613 ][WARNING ][12828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:51:53,860 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=enable_http_proxy value=True
2019-06-10 02:51:53,914 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=upstream_dns value=8.8.8.8
2019-06-10 02:51:53,980 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=commissioning_distro_series value=xenial
2019-06-10 02:51:54,040 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=default_osystem value=ubuntu
2019-06-10 02:51:55,391 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=active_discovery_interval value=600
2019-06-10 02:51:55,448 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=dnssec_validation value=no
2019-06-10 02:51:55,496 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=maas_name value=mas01
2019-06-10 02:51:55,539 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=network_discovery value=enabled
2019-06-10 02:51:55,632 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=enable_third_party_drivers value=True
2019-06-10 02:51:55,685 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=default_storage_layout value=lvm
2019-06-10 02:51:55,736 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=ntp_external_only value=True
2019-06-10 02:51:55,805 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-10 02:51:55,856 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=default_distro_series value=xenial
2019-06-10 02:51:55,932 [salt.loaded.ext.module.maas:92  ][INFO    ][12828] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-06-10 02:51:56,049 [salt.state       :300 ][INFO    ][12828] {'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-10 02:51:56,049 [salt.state       :1951][INFO    ][12828] Completed state [maas.process_maas_config] at time 02:51:56.049568 duration_in_ms=2193.11
2019-06-10 02:51:56,051 [salt.state       :1780][INFO    ][12828] Running state [pxe_admin] at time 02:51:56.050895
2019-06-10 02:51:56,051 [salt.state       :1813][INFO    ][12828] Executing state maasng.fabric_present for [pxe_admin]
2019-06-10 02:51:56,108 [salt.loaded.ext.module.maasng:945 ][INFO    ][12828] [{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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'tb63f6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-10 02:51:56,170 [salt.loaded.ext.module.maasng:1008][WARNING ][12828] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-10 02:51:56,171 [salt.loaded.ext.module.maasng:1011][WARNING ][12828] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-10 02:51:56,236 [salt.state       :300 ][INFO    ][12828] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-10 02:51:56,236 [salt.state       :1951][INFO    ][12828] Completed state [pxe_admin] at time 02:51:56.236327 duration_in_ms=185.433
2019-06-10 02:51:56,236 [salt.state       :1780][INFO    ][12828] Running state [vlan 0] at time 02:51:56.236700
2019-06-10 02:51:56,237 [salt.state       :1813][INFO    ][12828] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-10 02:51:56,295 [salt.loaded.ext.module.maasng:945 ][INFO    ][12828] [{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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'tb63f6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-10 02:51:56,406 [salt.loaded.ext.module.maasng:945 ][INFO    ][12828] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-10 02:51:56,649 [salt.loaded.ext.module.maasng:945 ][INFO    ][12828] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-06-10 02:51:56,732 [salt.state       :300 ][INFO    ][12828] {'new': 'Vlan untagged was updated'}
2019-06-10 02:51:56,733 [salt.state       :1951][INFO    ][12828] Completed state [vlan 0] at time 02:51:56.733171 duration_in_ms=496.471
2019-06-10 02:51:56,735 [salt.state       :1780][INFO    ][12828] Running state [192.168.11.0/24] at time 02:51:56.735054
2019-06-10 02:51:56,735 [salt.state       :1813][INFO    ][12828] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-10 02:51:56,921 [salt.loaded.ext.module.maasng:945 ][INFO    ][12828] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'tb63f6', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-10 02:51:56,922 [salt.loaded.ext.module.maasng:1235][WARNING ][12828] Ignoring parameter vlan:0
2019-06-10 02:51:56,993 [salt.state       :300 ][INFO    ][12828] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-10 02:51:56,993 [salt.state       :1951][INFO    ][12828] Completed state [192.168.11.0/24] at time 02:51:56.993362 duration_in_ms=258.308
2019-06-10 02:51:56,994 [salt.state       :1780][INFO    ][12828] Running state [maas_create_iprange_1] at time 02:51:56.994794
2019-06-10 02:51:56,995 [salt.state       :1813][INFO    ][12828] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-10 02:51:57,039 [salt.state       :300 ][INFO    ][12828] Iprange maas_create_iprange_1 already exist.
2019-06-10 02:51:57,039 [salt.state       :1951][INFO    ][12828] Completed state [maas_create_iprange_1] at time 02:51:57.039548 duration_in_ms=44.754
2019-06-10 02:51:57,039 [salt.state       :1780][INFO    ][12828] Running state [vlan 0] at time 02:51:57.039850
2019-06-10 02:51:57,040 [salt.state       :1813][INFO    ][12828] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-10 02:51:57,088 [salt.loaded.ext.module.maasng:945 ][INFO    ][12828] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'tb63f6', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-10 02:51:57,202 [salt.loaded.ext.module.maasng:945 ][INFO    ][12828] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'tb63f6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-06-10 02:51:57,689 [salt.loaded.ext.module.maasng:945 ][INFO    ][12828] [{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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'tb63f6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-06-10 02:51:57,771 [salt.state       :300 ][INFO    ][12828] {'new': 'Vlan untagged was updated'}
2019-06-10 02:51:57,772 [salt.state       :1951][INFO    ][12828] Completed state [vlan 0] at time 02:51:57.772114 duration_in_ms=732.262
2019-06-10 02:51:57,773 [salt.state       :1780][INFO    ][12828] Running state [opnfv] at time 02:51:57.773156
2019-06-10 02:51:57,773 [salt.state       :1813][INFO    ][12828] Executing state maasng.sshkey_present for [opnfv]
2019-06-10 02:51:57,825 [salt.loaded.ext.module.maasng:1903][INFO    ][12828] [{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-10 02:51:57,826 [salt.state       :300 ][INFO    ][12828] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-10 02:51:57,826 [salt.state       :1951][INFO    ][12828] Completed state [opnfv] at time 02:51:57.826552 duration_in_ms=53.396
2019-06-10 02:51:57,832 [salt.minion      :1711][INFO    ][12828] Returning information for job: 20190610025132734841
2019-06-10 02:51:58,588 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610025158578911
2019-06-10 02:51:58,605 [salt.minion      :1432][INFO    ][13284] Starting a new job with PID 13284
2019-06-10 02:52:04,743 [salt.state       :915 ][INFO    ][13284] Loading fresh modules for state activity
2019-06-10 02:52:04,870 [salt.state       :1780][INFO    ][13284] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:04.870176
2019-06-10 02:52:04,870 [salt.state       :1813][INFO    ][13284] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-10 02:52:04,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13284] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-10 02:52:06,797 [salt.state       :300 ][INFO    ][13284] {'pid': 13308, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-10 02:52:06,798 [salt.state       :1951][INFO    ][13284] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:06.798500 duration_in_ms=1928.324
2019-06-10 02:52:06,801 [salt.state       :1780][INFO    ][13284] Running state [maas.process_machines] at time 02:52:06.801632
2019-06-10 02:52:06,802 [salt.state       :1813][INFO    ][13284] Executing state module.run for [maas.process_machines]
2019-06-10 02:52:06,803 [salt.utils.decorators:613 ][WARNING ][13284] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:52:07,489 [salt.loaded.ext.module.maas:412 ][WARNING ][13284] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-10 02:52:07,491 [salt.loaded.ext.module.maas:92  ][INFO    ][13284] 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=p78wqg architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-10 02:52:08,746 [salt.loaded.ext.module.maas:412 ][WARNING ][13284] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-10 02:52:08,747 [salt.loaded.ext.module.maas:92  ][INFO    ][13284] 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=m4dpge architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-10 02:52:09,943 [salt.loaded.ext.module.maas:412 ][WARNING ][13284] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-10 02:52:09,944 [salt.loaded.ext.module.maas:92  ][INFO    ][13284] 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=mekswm architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-10 02:52:11,066 [salt.loaded.ext.module.maas:412 ][WARNING ][13284] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-10 02:52:11,067 [salt.loaded.ext.module.maas:92  ][INFO    ][13284] 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=m78cfk architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-10 02:52:12,338 [salt.loaded.ext.module.maas:412 ][WARNING ][13284] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-10 02:52:12,339 [salt.loaded.ext.module.maas:92  ][INFO    ][13284] 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=ftr3fb architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-10 02:52:13,374 [salt.state       :300 ][INFO    ][13284] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-10 02:52:13,374 [salt.state       :1951][INFO    ][13284] Completed state [maas.process_machines] at time 02:52:13.374869 duration_in_ms=6573.237
2019-06-10 02:52:13,378 [salt.minion      :1711][INFO    ][13284] Returning information for job: 20190610025158578911
2019-06-10 02:52:47,333 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610025247319711
2019-06-10 02:52:47,362 [salt.minion      :1432][INFO    ][13568] Starting a new job with PID 13568
2019-06-10 02:52:53,456 [salt.state       :915 ][INFO    ][13568] Loading fresh modules for state activity
2019-06-10 02:52:53,575 [salt.state       :1780][INFO    ][13568] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:53.575067
2019-06-10 02:52:53,575 [salt.state       :1813][INFO    ][13568] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-10 02:52:53,577 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13568] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-10 02:52:55,529 [salt.state       :300 ][INFO    ][13568] {'pid': 13588, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-10 02:52:55,530 [salt.state       :1951][INFO    ][13568] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:52:55.530396 duration_in_ms=1955.328
2019-06-10 02:52:55,534 [salt.state       :1780][INFO    ][13568] Running state [maas.wait_for_machine_status] at time 02:52:55.534450
2019-06-10 02:52:55,535 [salt.state       :1813][INFO    ][13568] Executing state module.run for [maas.wait_for_machine_status]
2019-06-10 02:52:55,536 [salt.utils.decorators:613 ][WARNING ][13568] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:52:58,353 [salt.state       :300 ][INFO    ][13568] {'ret': True}
2019-06-10 02:52:58,353 [salt.state       :1951][INFO    ][13568] Completed state [maas.wait_for_machine_status] at time 02:52:58.353478 duration_in_ms=2819.027
2019-06-10 02:52:58,357 [salt.minion      :1711][INFO    ][13568] Returning information for job: 20190610025247319711
2019-06-10 02:52:59,091 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610025259077116
2019-06-10 02:52:59,111 [salt.minion      :1432][INFO    ][13603] Starting a new job with PID 13603
2019-06-10 02:53:00,082 [salt.state       :915 ][INFO    ][13603] Loading fresh modules for state activity
2019-06-10 02:53:00,241 [salt.state       :1780][INFO    ][13603] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:00.241138
2019-06-10 02:53:00,241 [salt.state       :1813][INFO    ][13603] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-10 02:53:00,243 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13603] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-10 02:53:02,132 [salt.state       :300 ][INFO    ][13603] {'pid': 13610, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-10 02:53:02,133 [salt.state       :1951][INFO    ][13603] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:02.133119 duration_in_ms=1891.979
2019-06-10 02:53:02,139 [salt.state       :1780][INFO    ][13603] Running state [maas_machines_storage_cmp002_lvm] at time 02:53:02.139700
2019-06-10 02:53:02,140 [salt.state       :1813][INFO    ][13603] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-10 02:53:02,703 [salt.state       :300 ][INFO    ][13603] Machine cmp002 is not in Ready state.
2019-06-10 02:53:02,703 [salt.state       :1951][INFO    ][13603] Completed state [maas_machines_storage_cmp002_lvm] at time 02:53:02.703835 duration_in_ms=564.135
2019-06-10 02:53:02,704 [salt.state       :1780][INFO    ][13603] Running state [maas_machines_storage_cmp001_lvm] at time 02:53:02.704182
2019-06-10 02:53:02,704 [salt.state       :1813][INFO    ][13603] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-10 02:53:03,240 [salt.state       :300 ][INFO    ][13603] Machine cmp001 is not in Ready state.
2019-06-10 02:53:03,241 [salt.state       :1951][INFO    ][13603] Completed state [maas_machines_storage_cmp001_lvm] at time 02:53:03.241214 duration_in_ms=537.031
2019-06-10 02:53:03,247 [salt.minion      :1711][INFO    ][13603] Returning information for job: 20190610025259077116
2019-06-10 02:53:03,967 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610025303952953
2019-06-10 02:53:03,994 [salt.minion      :1432][INFO    ][13623] Starting a new job with PID 13623
2019-06-10 02:53:04,977 [salt.state       :915 ][INFO    ][13623] Loading fresh modules for state activity
2019-06-10 02:53:05,106 [salt.state       :1780][INFO    ][13623] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:05.106501
2019-06-10 02:53:05,106 [salt.state       :1813][INFO    ][13623] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-10 02:53:05,108 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13623] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-10 02:53:07,030 [salt.state       :300 ][INFO    ][13623] {'pid': 13630, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-10 02:53:07,031 [salt.state       :1951][INFO    ][13623] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:07.031724 duration_in_ms=1925.222
2019-06-10 02:53:07,037 [salt.state       :1780][INFO    ][13623] Running state [maas.deploy_machines] at time 02:53:07.037253
2019-06-10 02:53:07,038 [salt.state       :1813][INFO    ][13623] Executing state module.run for [maas.deploy_machines]
2019-06-10 02:53:07,039 [salt.utils.decorators:613 ][WARNING ][13623] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:53:07,674 [salt.loaded.ext.module.maas:684 ][INFO    ][13623] deploymachines hwe_kernel=ga-16.04 system_id=ftr3fb distro_series=xenial
2019-06-10 02:53:10,198 [salt.state       :300 ][INFO    ][13623] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-10 02:53:10,199 [salt.state       :1951][INFO    ][13623] Completed state [maas.deploy_machines] at time 02:53:10.199022 duration_in_ms=3161.767
2019-06-10 02:53:10,204 [salt.minion      :1711][INFO    ][13623] Returning information for job: 20190610025303952953
2019-06-10 02:53:10,930 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command state.apply with jid 20190610025310917100
2019-06-10 02:53:10,955 [salt.minion      :1432][INFO    ][13719] Starting a new job with PID 13719
2019-06-10 02:53:16,993 [salt.state       :915 ][INFO    ][13719] Loading fresh modules for state activity
2019-06-10 02:53:17,096 [salt.state       :1780][INFO    ][13719] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:17.096077
2019-06-10 02:53:17,096 [salt.state       :1813][INFO    ][13719] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-10 02:53:17,099 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13719] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-10 02:53:18,955 [salt.state       :300 ][INFO    ][13719] {'pid': 13730, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-10 02:53:18,956 [salt.state       :1951][INFO    ][13719] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:53:18.955907 duration_in_ms=1859.831
2019-06-10 02:53:18,959 [salt.state       :1780][INFO    ][13719] Running state [maas.wait_for_machine_status] at time 02:53:18.959031
2019-06-10 02:53:18,959 [salt.state       :1813][INFO    ][13719] Executing state module.run for [maas.wait_for_machine_status]
2019-06-10 02:53:18,960 [salt.utils.decorators:613 ][WARNING ][13719] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-10 02:53:21,630 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.34636402s left)
2019-06-10 02:53:26,110 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025326040438
2019-06-10 02:53:26,140 [salt.minion      :1432][INFO    ][13748] Starting a new job with PID 13748
2019-06-10 02:53:26,170 [salt.minion      :1711][INFO    ][13748] Returning information for job: 20190610025326040438
2019-06-10 02:53:54,592 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.38414907s left)
2019-06-10 02:53:56,211 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025356200711
2019-06-10 02:53:56,240 [salt.minion      :1432][INFO    ][13782] Starting a new job with PID 13782
2019-06-10 02:53:56,268 [salt.minion      :1711][INFO    ][13782] Returning information for job: 20190610025356200711
2019-06-10 02:54:26,328 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025426320461
2019-06-10 02:54:26,350 [salt.minion      :1432][INFO    ][13826] Starting a new job with PID 13826
2019-06-10 02:54:26,382 [salt.minion      :1711][INFO    ][13826] Returning information for job: 20190610025426320461
2019-06-10 02:54:27,400 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.57660103s left)
2019-06-10 02:54:56,416 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025456401408
2019-06-10 02:54:56,443 [salt.minion      :1432][INFO    ][13860] Starting a new job with PID 13860
2019-06-10 02:54:56,470 [salt.minion      :1711][INFO    ][13860] Returning information for job: 20190610025456401408
2019-06-10 02:55:00,282 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.69411612s left)
2019-06-10 02:55:26,509 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025526498540
2019-06-10 02:55:26,538 [salt.minion      :1432][INFO    ][13919] Starting a new job with PID 13919
2019-06-10 02:55:26,568 [salt.minion      :1711][INFO    ][13919] Returning information for job: 20190610025526498540
2019-06-10 02:55:32,939 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.03698206s left)
2019-06-10 02:55:56,604 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025556591354
2019-06-10 02:55:56,634 [salt.minion      :1432][INFO    ][13953] Starting a new job with PID 13953
2019-06-10 02:55:56,665 [salt.minion      :1711][INFO    ][13953] Returning information for job: 20190610025556591354
2019-06-10 02:56:05,677 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.29945898s left)
2019-06-10 02:56:26,708 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025626690713
2019-06-10 02:56:26,735 [salt.minion      :1432][INFO    ][13998] Starting a new job with PID 13998
2019-06-10 02:56:26,771 [salt.minion      :1711][INFO    ][13998] Returning information for job: 20190610025626690713
2019-06-10 02:56:38,720 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.25627303s left)
2019-06-10 02:56:56,802 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025656789526
2019-06-10 02:56:56,836 [salt.minion      :1432][INFO    ][14035] Starting a new job with PID 14035
2019-06-10 02:56:56,870 [salt.minion      :1711][INFO    ][14035] Returning information for job: 20190610025656789526
2019-06-10 02:57:11,465 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.51130199s left)
2019-06-10 02:57:26,926 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025726916520
2019-06-10 02:57:26,946 [salt.minion      :1432][INFO    ][14110] Starting a new job with PID 14110
2019-06-10 02:57:26,980 [salt.minion      :1711][INFO    ][14110] Returning information for job: 20190610025726916520
2019-06-10 02:57:44,409 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.56776214s left)
2019-06-10 02:57:57,033 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025757019169
2019-06-10 02:57:57,064 [salt.minion      :1432][INFO    ][14174] Starting a new job with PID 14174
2019-06-10 02:57:57,103 [salt.minion      :1711][INFO    ][14174] Returning information for job: 20190610025757019169
2019-06-10 02:58:17,303 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.67304206s left)
2019-06-10 02:58:27,175 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025827159117
2019-06-10 02:58:27,204 [salt.minion      :1432][INFO    ][14281] Starting a new job with PID 14281
2019-06-10 02:58:27,245 [salt.minion      :1711][INFO    ][14281] Returning information for job: 20190610025827159117
2019-06-10 02:58:50,093 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.88382816s left)
2019-06-10 02:58:57,309 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025857294749
2019-06-10 02:58:57,337 [salt.minion      :1432][INFO    ][14353] Starting a new job with PID 14353
2019-06-10 02:58:57,371 [salt.minion      :1711][INFO    ][14353] Returning information for job: 20190610025857294749
2019-06-10 02:59:23,024 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.95209408s left)
2019-06-10 02:59:27,457 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025927442122
2019-06-10 02:59:27,485 [salt.minion      :1432][INFO    ][14465] Starting a new job with PID 14465
2019-06-10 02:59:27,519 [salt.minion      :1711][INFO    ][14465] Returning information for job: 20190610025927442122
2019-06-10 02:59:55,997 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.97929597s left)
2019-06-10 02:59:57,584 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610025957573051
2019-06-10 02:59:57,609 [salt.minion      :1432][INFO    ][14505] Starting a new job with PID 14505
2019-06-10 02:59:57,655 [salt.minion      :1711][INFO    ][14505] Returning information for job: 20190610025957573051
2019-06-10 03:00:27,753 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610030027743194
2019-06-10 03:00:27,779 [salt.minion      :1432][INFO    ][14589] Starting a new job with PID 14589
2019-06-10 03:00:27,815 [salt.minion      :1711][INFO    ][14589] Returning information for job: 20190610030027743194
2019-06-10 03:00:28,990 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.9866271s left)
2019-06-10 03:00:57,919 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610030057907045
2019-06-10 03:00:57,944 [salt.minion      :1432][INFO    ][14643] Starting a new job with PID 14643
2019-06-10 03:00:57,979 [salt.minion      :1711][INFO    ][14643] Returning information for job: 20190610030057907045
2019-06-10 03:01:01,783 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.19304204s left)
2019-06-10 03:01:28,089 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610030128077086
2019-06-10 03:01:28,117 [salt.minion      :1432][INFO    ][14747] Starting a new job with PID 14747
2019-06-10 03:01:28,144 [salt.minion      :1711][INFO    ][14747] Returning information for job: 20190610030128077086
2019-06-10 03:01:34,579 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.39704299s left)
2019-06-10 03:01:58,261 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610030158246508
2019-06-10 03:01:58,294 [salt.minion      :1432][INFO    ][14802] Starting a new job with PID 14802
2019-06-10 03:01:58,324 [salt.minion      :1711][INFO    ][14802] Returning information for job: 20190610030158246508
2019-06-10 03:02:07,761 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.21574211s left)
2019-06-10 03:02:28,436 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610030228423715
2019-06-10 03:02:28,463 [salt.minion      :1432][INFO    ][14881] Starting a new job with PID 14881
2019-06-10 03:02:28,498 [salt.minion      :1711][INFO    ][14881] Returning information for job: 20190610030228423715
2019-06-10 03:02:40,771 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1688.20512605s left)
2019-06-10 03:02:58,624 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610030258608251
2019-06-10 03:02:58,651 [salt.minion      :1432][INFO    ][14916] Starting a new job with PID 14916
2019-06-10 03:02:58,681 [salt.minion      :1711][INFO    ][14916] Returning information for job: 20190610030258608251
2019-06-10 03:03:13,403 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1655.57361317s left)
2019-06-10 03:03:28,811 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610030328796443
2019-06-10 03:03:28,837 [salt.minion      :1432][INFO    ][14963] Starting a new job with PID 14963
2019-06-10 03:03:28,869 [salt.minion      :1711][INFO    ][14963] Returning information for job: 20190610030328796443
2019-06-10 03:03:46,374 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1622.60345197s left)
2019-06-10 03:03:59,003 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610030358984391
2019-06-10 03:03:59,028 [salt.minion      :1432][INFO    ][14995] Starting a new job with PID 14995
2019-06-10 03:03:59,063 [salt.minion      :1711][INFO    ][14995] Returning information for job: 20190610030358984391
2019-06-10 03:04:19,278 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1589.69851017s left)
2019-06-10 03:04:29,192 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610030429176877
2019-06-10 03:04:29,224 [salt.minion      :1432][INFO    ][15042] Starting a new job with PID 15042
2019-06-10 03:04:29,255 [salt.minion      :1711][INFO    ][15042] Returning information for job: 20190610030429176877
2019-06-10 03:04:52,236 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.74018312s left)
2019-06-10 03:04:59,386 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610030459379201
2019-06-10 03:04:59,417 [salt.minion      :1432][INFO    ][15081] Starting a new job with PID 15081
2019-06-10 03:04:59,426 [salt.utils.schedule:1377][INFO    ][3058] Running scheduled job: __mine_interval
2019-06-10 03:04:59,448 [salt.minion      :1711][INFO    ][15081] Returning information for job: 20190610030459379201
2019-06-10 03:05:24,959 [salt.loaded.ext.module.maas:1023][INFO    ][13719] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1524.01771903s left)
2019-06-10 03:05:29,601 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command saltutil.find_job with jid 20190610030529590716
2019-06-10 03:05:29,635 [salt.minion      :1432][INFO    ][15315] Starting a new job with PID 15315
2019-06-10 03:05:29,667 [salt.minion      :1711][INFO    ][15315] Returning information for job: 20190610030529590716
2019-06-10 03:05:57,996 [salt.state       :300 ][INFO    ][13719] {'ret': True}
2019-06-10 03:05:57,997 [salt.state       :1951][INFO    ][13719] Completed state [maas.wait_for_machine_status] at time 03:05:57.996871 duration_in_ms=759037.834
2019-06-10 03:05:58,005 [salt.minion      :1711][INFO    ][13719] Returning information for job: 20190610025310917100
2019-06-10 04:04:59,425 [salt.utils.schedule:1377][INFO    ][3058] Running scheduled job: __mine_interval
2019-06-10 04:35:14,372 [salt.minion      :1308][INFO    ][3058] User sudo_ubuntu Executing command cp.push_dir with jid 20190610043514359259
2019-06-10 04:35:14,395 [salt.minion      :1432][INFO    ][21727] Starting a new job with PID 21727
