2019-05-21 20:13:14,477 [salt.utils.decorators:613 ][WARNING ][2088] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 20:13:15,345 [salt.utils.decorators:613 ][WARNING ][2088] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 20:13:18,898 [salt.loaded.int.states.file:2298][WARNING ][2529] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-21 20:13:36,262 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2888] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-21 20:13:36,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2888] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-21 20:13:36,328 [salt.utils.parsers:1051][WARNING ][387] Minion received a SIGTERM. Exiting.
2019-05-21 20:13:37,434 [salt.cli.daemons :293 ][INFO    ][3001] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-05-21 20:13:37,605 [salt.cli.daemons :82  ][INFO    ][3001] Starting up the Salt Minion
2019-05-21 20:13:37,606 [salt.utils.event :1017][INFO    ][3001] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-05-21 20:13:38,728 [salt.minion      :976 ][INFO    ][3001] Creating minion process manager
2019-05-21 20:13:40,795 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3001] Executing command ['date', '+%z'] in directory '/root'
2019-05-21 20:13:40,819 [salt.utils.schedule:568 ][INFO    ][3001] Updating job settings for scheduled job: __mine_interval
2019-05-21 20:13:40,821 [salt.minion      :1108][INFO    ][3001] Added mine.update to scheduler
2019-05-21 20:13:40,829 [salt.minion      :1975][INFO    ][3001] Minion is starting as user 'root'
2019-05-21 20:13:40,840 [salt.minion      :2336][INFO    ][3001] Minion is ready to receive requests!
2019-05-21 20:13:47,323 [salt.state       :2022][WARNING ][2894] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-21 20:13:48,936 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201348928040
2019-05-21 20:13:48,954 [salt.minion      :1432][INFO    ][3446] Starting a new job with PID 3446
2019-05-21 20:13:48,983 [salt.minion      :1711][INFO    ][3446] Returning information for job: 20190521201348928040
2019-05-21 20:13:49,467 [salt.utils.decorators:613 ][WARNING ][2894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 20:14:18,989 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201418977173
2019-05-21 20:14:19,008 [salt.minion      :1432][INFO    ][3656] Starting a new job with PID 3656
2019-05-21 20:14:19,035 [salt.minion      :1711][INFO    ][3656] Returning information for job: 20190521201418977173
2019-05-21 20:14:49,036 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201449027421
2019-05-21 20:14:49,050 [salt.minion      :1432][INFO    ][3811] Starting a new job with PID 3811
2019-05-21 20:14:49,076 [salt.minion      :1711][INFO    ][3811] Returning information for job: 20190521201449027421
2019-05-21 20:15:19,258 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201519246686
2019-05-21 20:15:19,272 [salt.minion      :1432][INFO    ][3990] Starting a new job with PID 3990
2019-05-21 20:15:19,300 [salt.minion      :1711][INFO    ][3990] Returning information for job: 20190521201519246686
2019-05-21 20:15:37,555 [salt.utils.decorators:613 ][WARNING ][2894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 20:15:49,340 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201549327646
2019-05-21 20:15:49,367 [salt.minion      :1432][INFO    ][4390] Starting a new job with PID 4390
2019-05-21 20:15:49,404 [salt.minion      :1711][INFO    ][4390] Returning information for job: 20190521201549327646
2019-05-21 20:16:19,399 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201619388806
2019-05-21 20:16:19,415 [salt.minion      :1432][INFO    ][4468] Starting a new job with PID 4468
2019-05-21 20:16:19,436 [salt.minion      :1711][INFO    ][4468] Returning information for job: 20190521201619388806
2019-05-21 20:16:49,411 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201649403475
2019-05-21 20:16:49,426 [salt.minion      :1432][INFO    ][4522] Starting a new job with PID 4522
2019-05-21 20:16:49,448 [salt.minion      :1711][INFO    ][4522] Returning information for job: 20190521201649403475
2019-05-21 20:17:19,444 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201719432172
2019-05-21 20:17:19,463 [salt.minion      :1432][INFO    ][4562] Starting a new job with PID 4562
2019-05-21 20:17:19,487 [salt.minion      :1711][INFO    ][4562] Returning information for job: 20190521201719432172
2019-05-21 20:17:49,462 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201749454907
2019-05-21 20:17:49,476 [salt.minion      :1432][INFO    ][4610] Starting a new job with PID 4610
2019-05-21 20:17:49,499 [salt.minion      :1711][INFO    ][4610] Returning information for job: 20190521201749454907
2019-05-21 20:18:19,477 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201819469728
2019-05-21 20:18:19,499 [salt.minion      :1432][INFO    ][4664] Starting a new job with PID 4664
2019-05-21 20:18:19,535 [salt.minion      :1711][INFO    ][4664] Returning information for job: 20190521201819469728
2019-05-21 20:18:49,499 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201849492255
2019-05-21 20:18:49,512 [salt.minion      :1432][INFO    ][4714] Starting a new job with PID 4714
2019-05-21 20:18:49,535 [salt.minion      :1711][INFO    ][4714] Returning information for job: 20190521201849492255
2019-05-21 20:19:19,532 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201919519066
2019-05-21 20:19:19,555 [salt.minion      :1432][INFO    ][4758] Starting a new job with PID 4758
2019-05-21 20:19:19,578 [salt.minion      :1711][INFO    ][4758] Returning information for job: 20190521201919519066
2019-05-21 20:19:49,567 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521201949560073
2019-05-21 20:19:49,583 [salt.minion      :1432][INFO    ][4795] Starting a new job with PID 4795
2019-05-21 20:19:49,604 [salt.minion      :1711][INFO    ][4795] Returning information for job: 20190521201949560073
2019-05-21 20:20:19,581 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202019569446
2019-05-21 20:20:19,605 [salt.minion      :1432][INFO    ][4846] Starting a new job with PID 4846
2019-05-21 20:20:19,630 [salt.minion      :1711][INFO    ][4846] Returning information for job: 20190521202019569446
2019-05-21 20:20:49,628 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202049619274
2019-05-21 20:20:49,645 [salt.minion      :1432][INFO    ][4882] Starting a new job with PID 4882
2019-05-21 20:20:49,672 [salt.minion      :1711][INFO    ][4882] Returning information for job: 20190521202049619274
2019-05-21 20:21:19,689 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202119681554
2019-05-21 20:21:19,703 [salt.minion      :1432][INFO    ][4929] Starting a new job with PID 4929
2019-05-21 20:21:19,724 [salt.minion      :1711][INFO    ][4929] Returning information for job: 20190521202119681554
2019-05-21 20:21:49,704 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202149696195
2019-05-21 20:21:49,721 [salt.minion      :1432][INFO    ][4966] Starting a new job with PID 4966
2019-05-21 20:21:49,746 [salt.minion      :1711][INFO    ][4966] Returning information for job: 20190521202149696195
2019-05-21 20:22:19,759 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202219745107
2019-05-21 20:22:19,781 [salt.minion      :1432][INFO    ][5011] Starting a new job with PID 5011
2019-05-21 20:22:19,801 [salt.minion      :1711][INFO    ][5011] Returning information for job: 20190521202219745107
2019-05-21 20:22:49,911 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202249899456
2019-05-21 20:22:49,935 [salt.minion      :1432][INFO    ][5047] Starting a new job with PID 5047
2019-05-21 20:22:49,956 [salt.minion      :1711][INFO    ][5047] Returning information for job: 20190521202249899456
2019-05-21 20:23:20,084 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202320070131
2019-05-21 20:23:20,113 [salt.minion      :1432][INFO    ][5093] Starting a new job with PID 5093
2019-05-21 20:23:20,132 [salt.minion      :1711][INFO    ][5093] Returning information for job: 20190521202320070131
2019-05-21 20:23:50,251 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202350235462
2019-05-21 20:23:50,281 [salt.minion      :1432][INFO    ][5142] Starting a new job with PID 5142
2019-05-21 20:23:50,300 [salt.minion      :1711][INFO    ][5142] Returning information for job: 20190521202350235462
2019-05-21 20:24:20,433 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202420419345
2019-05-21 20:24:20,459 [salt.minion      :1432][INFO    ][5205] Starting a new job with PID 5205
2019-05-21 20:24:20,484 [salt.minion      :1711][INFO    ][5205] Returning information for job: 20190521202420419345
2019-05-21 20:24:50,599 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202450587269
2019-05-21 20:24:50,628 [salt.minion      :1432][INFO    ][5244] Starting a new job with PID 5244
2019-05-21 20:24:50,653 [salt.minion      :1711][INFO    ][5244] Returning information for job: 20190521202450587269
2019-05-21 20:25:20,794 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202520783102
2019-05-21 20:25:20,820 [salt.minion      :1432][INFO    ][5285] Starting a new job with PID 5285
2019-05-21 20:25:20,840 [salt.minion      :1711][INFO    ][5285] Returning information for job: 20190521202520783102
2019-05-21 20:25:50,984 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202550971732
2019-05-21 20:25:51,007 [salt.minion      :1432][INFO    ][5327] Starting a new job with PID 5327
2019-05-21 20:25:51,027 [salt.minion      :1711][INFO    ][5327] Returning information for job: 20190521202550971732
2019-05-21 20:26:21,184 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202621172717
2019-05-21 20:26:21,210 [salt.minion      :1432][INFO    ][5364] Starting a new job with PID 5364
2019-05-21 20:26:21,233 [salt.minion      :1711][INFO    ][5364] Returning information for job: 20190521202621172717
2019-05-21 20:26:51,400 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202651392236
2019-05-21 20:26:51,419 [salt.minion      :1432][INFO    ][5406] Starting a new job with PID 5406
2019-05-21 20:26:51,439 [salt.minion      :1711][INFO    ][5406] Returning information for job: 20190521202651392236
2019-05-21 20:27:05,892 [salt.utils.decorators:613 ][WARNING ][2894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 20:27:21,594 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202721583541
2019-05-21 20:27:21,617 [salt.minion      :1432][INFO    ][5476] Starting a new job with PID 5476
2019-05-21 20:27:21,639 [salt.minion      :1711][INFO    ][5476] Returning information for job: 20190521202721583541
2019-05-21 20:27:27,740 [salt.utils.decorators:613 ][WARNING ][2894] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 20:27:31,587 [salt.loaded.ext.module.maasng:1008][WARNING ][2894] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-21 20:27:31,587 [salt.loaded.ext.module.maasng:1011][WARNING ][2894] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-21 20:27:32,483 [salt.loaded.ext.module.maasng:1235][WARNING ][2894] Ignoring parameter vlan:0
2019-05-21 20:27:34,375 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521202734362411
2019-05-21 20:27:34,397 [salt.minion      :1432][INFO    ][5701] Starting a new job with PID 5701
2019-05-21 20:27:41,902 [salt.state       :915 ][INFO    ][5701] Loading fresh modules for state activity
2019-05-21 20:27:41,972 [salt.fileclient  :1219][INFO    ][5701] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-05-21 20:27:42,023 [salt.state       :1780][INFO    ][5701] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:27:42.023500
2019-05-21 20:27:42,023 [salt.state       :1813][INFO    ][5701] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 20:27:42,025 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5701] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 20:27:43,790 [salt.state       :300 ][INFO    ][5701] {'pid': 5739, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 20:27:43,791 [salt.state       :1951][INFO    ][5701] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:27:43.791541 duration_in_ms=1768.041
2019-05-21 20:27:43,795 [salt.state       :1780][INFO    ][5701] Running state [maas.process_machines] at time 20:27:43.795593
2019-05-21 20:27:43,796 [salt.state       :1813][INFO    ][5701] Executing state module.run for [maas.process_machines]
2019-05-21 20:27:43,797 [salt.utils.decorators:613 ][WARNING ][5701] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 20:27:43,869 [salt.loaded.ext.module.maas:412 ][WARNING ][5701] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 20:27:43,870 [salt.loaded.ext.module.maas:92  ][INFO    ][5701] 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-05-21 20:27:45,477 [salt.loaded.ext.module.maas:412 ][WARNING ][5701] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 20:27:45,478 [salt.loaded.ext.module.maas:92  ][INFO    ][5701] 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-05-21 20:27:46,915 [salt.loaded.ext.module.maas:412 ][WARNING ][5701] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 20:27:46,915 [salt.loaded.ext.module.maas:92  ][INFO    ][5701] 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-05-21 20:27:48,392 [salt.loaded.ext.module.maas:412 ][WARNING ][5701] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 20:27:48,392 [salt.loaded.ext.module.maas:92  ][INFO    ][5701] 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-05-21 20:27:49,464 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202749452017
2019-05-21 20:27:49,488 [salt.minion      :1432][INFO    ][5945] Starting a new job with PID 5945
2019-05-21 20:27:49,513 [salt.minion      :1711][INFO    ][5945] Returning information for job: 20190521202749452017
2019-05-21 20:27:49,867 [salt.loaded.ext.module.maas:412 ][WARNING ][5701] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 20:27:49,868 [salt.loaded.ext.module.maas:92  ][INFO    ][5701] 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-05-21 20:27:51,353 [salt.state       :300 ][INFO    ][5701] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-21 20:27:51,353 [salt.state       :1951][INFO    ][5701] Completed state [maas.process_machines] at time 20:27:51.353589 duration_in_ms=7557.996
2019-05-21 20:27:51,357 [salt.minion      :1711][INFO    ][5701] Returning information for job: 20190521202734362411
2019-05-21 20:28:22,444 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521202822428882
2019-05-21 20:28:22,471 [salt.minion      :1432][INFO    ][6072] Starting a new job with PID 6072
2019-05-21 20:28:29,815 [salt.state       :915 ][INFO    ][6072] Loading fresh modules for state activity
2019-05-21 20:28:29,879 [salt.fileclient  :1219][INFO    ][6072] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-21 20:28:29,928 [salt.state       :1780][INFO    ][6072] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:28:29.928162
2019-05-21 20:28:29,928 [salt.state       :1813][INFO    ][6072] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 20:28:29,930 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6072] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 20:28:31,727 [salt.state       :300 ][INFO    ][6072] {'pid': 6079, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 20:28:31,728 [salt.state       :1951][INFO    ][6072] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:28:31.728633 duration_in_ms=1800.47
2019-05-21 20:28:31,733 [salt.state       :1780][INFO    ][6072] Running state [maas.wait_for_machine_status] at time 20:28:31.733558
2019-05-21 20:28:31,734 [salt.state       :1813][INFO    ][6072] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 20:28:31,735 [salt.utils.decorators:613 ][WARNING ][6072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 20:28:32,672 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.07463908s left)
2019-05-21 20:28:37,576 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202837560284
2019-05-21 20:28:37,601 [salt.minion      :1432][INFO    ][6102] Starting a new job with PID 6102
2019-05-21 20:28:37,624 [salt.minion      :1711][INFO    ][6102] Returning information for job: 20190521202837560284
2019-05-21 20:29:03,471 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.27558708s left)
2019-05-21 20:29:07,657 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202907640466
2019-05-21 20:29:07,686 [salt.minion      :1432][INFO    ][6133] Starting a new job with PID 6133
2019-05-21 20:29:07,708 [salt.minion      :1711][INFO    ][6133] Returning information for job: 20190521202907640466
2019-05-21 20:29:34,309 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.43793392s left)
2019-05-21 20:29:37,792 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521202937774876
2019-05-21 20:29:37,821 [salt.minion      :1432][INFO    ][6176] Starting a new job with PID 6176
2019-05-21 20:29:37,842 [salt.minion      :1711][INFO    ][6176] Returning information for job: 20190521202937774876
2019-05-21 20:30:05,156 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.59121013s left)
2019-05-21 20:30:07,882 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203007864377
2019-05-21 20:30:07,904 [salt.minion      :1432][INFO    ][6208] Starting a new job with PID 6208
2019-05-21 20:30:07,927 [salt.minion      :1711][INFO    ][6208] Returning information for job: 20190521203007864377
2019-05-21 20:30:36,239 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.50821114s left)
2019-05-21 20:30:37,960 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203037940683
2019-05-21 20:30:37,988 [salt.minion      :1432][INFO    ][6284] Starting a new job with PID 6284
2019-05-21 20:30:38,012 [salt.minion      :1711][INFO    ][6284] Returning information for job: 20190521203037940683
2019-05-21 20:31:07,485 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.26241302s left)
2019-05-21 20:31:08,036 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203108020336
2019-05-21 20:31:08,065 [salt.minion      :1432][INFO    ][6421] Starting a new job with PID 6421
2019-05-21 20:31:08,100 [salt.minion      :1711][INFO    ][6421] Returning information for job: 20190521203108020336
2019-05-21 20:31:38,141 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203138129152
2019-05-21 20:31:38,166 [salt.minion      :1432][INFO    ][6550] Starting a new job with PID 6550
2019-05-21 20:31:38,188 [salt.minion      :1711][INFO    ][6550] Returning information for job: 20190521203138129152
2019-05-21 20:31:38,652 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.09509492s left)
2019-05-21 20:32:08,240 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203208229175
2019-05-21 20:32:08,262 [salt.minion      :1432][INFO    ][6825] Starting a new job with PID 6825
2019-05-21 20:32:08,284 [salt.minion      :1711][INFO    ][6825] Returning information for job: 20190521203208229175
2019-05-21 20:32:10,074 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.67260909s left)
2019-05-21 20:32:38,314 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203238298547
2019-05-21 20:32:38,346 [salt.minion      :1432][INFO    ][6960] Starting a new job with PID 6960
2019-05-21 20:32:38,370 [salt.minion      :1711][INFO    ][6960] Returning information for job: 20190521203238298547
2019-05-21 20:32:41,858 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.88872409s left)
2019-05-21 20:33:08,469 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203308453354
2019-05-21 20:33:08,496 [salt.minion      :1432][INFO    ][7227] Starting a new job with PID 7227
2019-05-21 20:33:08,518 [salt.minion      :1711][INFO    ][7227] Returning information for job: 20190521203308453354
2019-05-21 20:33:14,053 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.69409013s left)
2019-05-21 20:33:38,600 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203338583921
2019-05-21 20:33:38,628 [salt.minion      :1432][INFO    ][7487] Starting a new job with PID 7487
2019-05-21 20:33:38,652 [salt.minion      :1711][INFO    ][7487] Returning information for job: 20190521203338583921
2019-05-21 20:33:46,392 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.35511303s left)
2019-05-21 20:34:08,741 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203408722512
2019-05-21 20:34:08,765 [salt.minion      :1432][INFO    ][7647] Starting a new job with PID 7647
2019-05-21 20:34:08,787 [salt.minion      :1711][INFO    ][7647] Returning information for job: 20190521203408722512
2019-05-21 20:34:18,753 [salt.loaded.ext.module.maas:1023][INFO    ][6072] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1152.99444413s left)
2019-05-21 20:34:38,858 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203438846223
2019-05-21 20:34:38,888 [salt.minion      :1432][INFO    ][7796] Starting a new job with PID 7796
2019-05-21 20:34:38,908 [salt.minion      :1711][INFO    ][7796] Returning information for job: 20190521203438846223
2019-05-21 20:34:52,234 [salt.state       :300 ][INFO    ][6072] {'ret': True}
2019-05-21 20:34:52,234 [salt.state       :1951][INFO    ][6072] Completed state [maas.wait_for_machine_status] at time 20:34:52.234645 duration_in_ms=380501.086
2019-05-21 20:34:52,241 [salt.minion      :1711][INFO    ][6072] Returning information for job: 20190521202822428882
2019-05-21 20:34:52,836 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521203452820322
2019-05-21 20:34:52,861 [salt.minion      :1432][INFO    ][7879] Starting a new job with PID 7879
2019-05-21 20:35:00,486 [salt.state       :915 ][INFO    ][7879] Loading fresh modules for state activity
2019-05-21 20:35:00,552 [salt.fileclient  :1219][INFO    ][7879] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-21 20:35:00,655 [salt.state       :1780][INFO    ][7879] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:35:00.655371
2019-05-21 20:35:00,655 [salt.state       :1813][INFO    ][7879] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 20:35:00,657 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7879] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 20:35:02,374 [salt.state       :300 ][INFO    ][7879] {'pid': 7886, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 20:35:02,375 [salt.state       :1951][INFO    ][7879] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:35:02.375544 duration_in_ms=1720.171
2019-05-21 20:35:02,381 [salt.state       :1780][INFO    ][7879] Running state [maas_machines_storage_cmp002_lvm] at time 20:35:02.381821
2019-05-21 20:35:02,382 [salt.state       :1813][INFO    ][7879] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-21 20:35:03,570 [salt.loaded.ext.module.maasng:610 ][INFO    ][7879] dhkd8f
2019-05-21 20:35:03,571 [salt.loaded.ext.module.maasng:626 ][INFO    ][7879] sda
2019-05-21 20:35:04,152 [salt.loaded.ext.module.maasng:361 ][INFO    ][7879] dhkd8f
2019-05-21 20:35:04,252 [salt.loaded.ext.module.maasng:367 ][INFO    ][7879] [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/blockdevices/1/', u'used_size': 800106479616, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dhkd8f', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 1, u'tags': [u'ssd'], u'partitions': [{u'uuid': u'94ccc1eb-c949-4f2c-83dc-336de18a1854', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dhkd8f', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f37c5a2b-ff04-4cf9-a724-f3197c4ea940', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/blockdevices/1/partition/1'}]}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/blockdevices/3/', u'used_size': 800097042432, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5d1d3d17-036e-4a00-8f06-cb3040544876', u'fstype': u'ext4'}, u'uuid': u'93c75f86-b0f0-4fb8-aee2-a5e7faa2e2c9', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dhkd8f', u'serial': None, u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'tags': [], u'partitions': []}]
2019-05-21 20:35:04,253 [salt.loaded.ext.module.maasng:632 ][INFO    ][7879] vgroot
2019-05-21 20:35:04,254 [salt.loaded.ext.module.maasng:635 ][INFO    ][7879] lvroot
2019-05-21 20:35:04,254 [salt.loaded.ext.module.maasng:639 ][INFO    ][7879] 107374182400
2019-05-21 20:35:04,904 [salt.loaded.ext.module.maasng:645 ][INFO    ][7879] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'owner_data': {}, u'hwe_kernel': u'', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'dhkd8f', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/interfaces/4/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'1093395d-27d6-4925-8aa3-d5a98e4805bb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dhkd8f', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'61d3be3e-274b-438a-a274-eeb7a8c9283a'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'dhkd8f', 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'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/blockdevices/1/'}, {u'size': 107374182400, u'uuid': u'4c4636bb-9422-4875-b96b-9f723c04d6a8', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'dhkd8f', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'92710d79-e057-479d-b1f6-45c28b83b409'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/blockdevices/11/'}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'dhkd8f', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'uuid': u'4c4636bb-9422-4875-b96b-9f723c04d6a8', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'dhkd8f', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'92710d79-e057-479d-b1f6-45c28b83b409'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/blockdevices/11/'}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'1093395d-27d6-4925-8aa3-d5a98e4805bb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dhkd8f', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'61d3be3e-274b-438a-a274-eeb7a8c9283a'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'dhkd8f', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/blockdevices/1/'}], 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'dhkd8f', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'1093395d-27d6-4925-8aa3-d5a98e4805bb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dhkd8f', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'61d3be3e-274b-438a-a274-eeb7a8c9283a'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'dhkd8f', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/blockdevices/1/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'dhkd8f', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'dhkd8f', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/interfaces/12/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'dhkd8f', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/interfaces/15/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'dhkd8f', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/interfaces/11/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'dhkd8f', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'dhkd8f', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/dhkd8f/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/dhkd8f/'}
2019-05-21 20:35:04,907 [salt.state       :300 ][INFO    ][7879] {'new': {'storage_layout': 'lvm'}}
2019-05-21 20:35:04,907 [salt.state       :1951][INFO    ][7879] Completed state [maas_machines_storage_cmp002_lvm] at time 20:35:04.907871 duration_in_ms=2526.048
2019-05-21 20:35:04,908 [salt.state       :1780][INFO    ][7879] Running state [maas_machines_storage_cmp001_lvm] at time 20:35:04.908424
2019-05-21 20:35:04,908 [salt.state       :1813][INFO    ][7879] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-21 20:35:06,066 [salt.loaded.ext.module.maasng:610 ][INFO    ][7879] td3taq
2019-05-21 20:35:06,067 [salt.loaded.ext.module.maasng:626 ][INFO    ][7879] sda
2019-05-21 20:35:06,648 [salt.loaded.ext.module.maasng:361 ][INFO    ][7879] td3taq
2019-05-21 20:35:06,746 [salt.loaded.ext.module.maasng:367 ][INFO    ][7879] [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'ee757ef5-f188-410d-92b7-417ed08089c5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'td3taq', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'af9ba7f3-f2ca-4e86-b857-3352e8523ff2'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/blockdevices/2/partition/2'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'td3taq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/blockdevices/2/'}, {u'size': 800097042432, u'uuid': u'8c6f596d-5570-48b1-adab-008db6feefa4', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'td3taq', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'92b5c5c3-c323-43c4-8f37-4b07f88a9f0b'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 4, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/blockdevices/4/'}]
2019-05-21 20:35:06,747 [salt.loaded.ext.module.maasng:632 ][INFO    ][7879] vgroot
2019-05-21 20:35:06,747 [salt.loaded.ext.module.maasng:635 ][INFO    ][7879] lvroot
2019-05-21 20:35:06,747 [salt.loaded.ext.module.maasng:639 ][INFO    ][7879] 107374182400
2019-05-21 20:35:07,343 [salt.loaded.ext.module.maasng:645 ][INFO    ][7879] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/blockdevices/2/', u'type': u'physical', u'partitions': [{u'uuid': u'4c27144a-816e-45ef-b91c-3dd22a044286', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'td3taq', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd1c3523b-f817-4938-8549-3b1fa6b179fa', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'td3taq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/td3taq/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'td3taq', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/blockdevices/2/', u'type': u'physical', u'partitions': [{u'uuid': u'4c27144a-816e-45ef-b91c-3dd22a044286', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'td3taq', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd1c3523b-f817-4938-8549-3b1fa6b179fa', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'td3taq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/blockdevices/12/', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'6e5c6335-e3c7-4f28-ab80-0de34f553976', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'td3taq', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'552deab3-d3a3-4289-ae0b-79428a2b7c47', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'td3taq', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/blockdevices/2/', u'type': u'physical', u'partitions': [{u'uuid': u'4c27144a-816e-45ef-b91c-3dd22a044286', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'td3taq', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'd1c3523b-f817-4938-8549-3b1fa6b179fa', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'td3taq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/blockdevices/12/', u'type': u'virtual', u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'td3taq', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'552deab3-d3a3-4289-ae0b-79428a2b7c47', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'uuid': u'6e5c6335-e3c7-4f28-ab80-0de34f553976'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6bwmdb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6bwmdb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6bwmdb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'td3taq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/interfaces/5/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6bwmdb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6bwmdb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6bwmdb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'td3taq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/interfaces/5/'}, {u'name': u'ens2f1', u'links': [{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': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cd', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'td3taq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/interfaces/19/'}, {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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6bwmdb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 28, 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': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6bwmdb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a4', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6bwmdb', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.44'}], u'parents': [], u'system_id': u'td3taq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/interfaces/20/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d4', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'td3taq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/interfaces/16/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d5', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'td3taq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/interfaces/17/'}, {u'name': u'ens2f0', 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cc', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'td3taq', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/td3taq/interfaces/18/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-05-21 20:35:07,347 [salt.state       :300 ][INFO    ][7879] {'new': {'storage_layout': 'lvm'}}
2019-05-21 20:35:07,348 [salt.state       :1951][INFO    ][7879] Completed state [maas_machines_storage_cmp001_lvm] at time 20:35:07.348097 duration_in_ms=2439.672
2019-05-21 20:35:07,352 [salt.minion      :1711][INFO    ][7879] Returning information for job: 20190521203452820322
2019-05-21 20:35:07,914 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521203507899919
2019-05-21 20:35:07,942 [salt.minion      :1432][INFO    ][7910] Starting a new job with PID 7910
2019-05-21 20:35:09,077 [salt.state       :915 ][INFO    ][7910] Loading fresh modules for state activity
2019-05-21 20:35:09,144 [salt.fileclient  :1219][INFO    ][7910] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-21 20:35:09,200 [salt.state       :1780][INFO    ][7910] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:35:09.200093
2019-05-21 20:35:09,200 [salt.state       :1813][INFO    ][7910] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 20:35:09,202 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7910] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 20:35:10,967 [salt.state       :300 ][INFO    ][7910] {'pid': 7917, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 20:35:10,968 [salt.state       :1951][INFO    ][7910] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:35:10.968536 duration_in_ms=1768.442
2019-05-21 20:35:10,972 [salt.state       :1780][INFO    ][7910] Running state [maas.deploy_machines] at time 20:35:10.972478
2019-05-21 20:35:10,973 [salt.state       :1813][INFO    ][7910] Executing state module.run for [maas.deploy_machines]
2019-05-21 20:35:10,975 [salt.utils.decorators:613 ][WARNING ][7910] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 20:35:11,564 [salt.loaded.ext.module.maas:684 ][INFO    ][7910] deploymachines hwe_kernel=hwe-16.04 system_id=dhkd8f distro_series=xenial
2019-05-21 20:35:14,158 [salt.loaded.ext.module.maas:684 ][INFO    ][7910] deploymachines hwe_kernel=hwe-16.04 system_id=td3taq distro_series=xenial
2019-05-21 20:35:16,744 [salt.loaded.ext.module.maas:684 ][INFO    ][7910] deploymachines hwe_kernel=hwe-16.04 system_id=tpqaap distro_series=xenial
2019-05-21 20:35:19,243 [salt.loaded.ext.module.maas:684 ][INFO    ][7910] deploymachines hwe_kernel=hwe-16.04 system_id=hs66dh distro_series=xenial
2019-05-21 20:35:21,935 [salt.loaded.ext.module.maas:684 ][INFO    ][7910] deploymachines hwe_kernel=hwe-16.04 system_id=mm8h66 distro_series=xenial
2019-05-21 20:35:23,035 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203523023080
2019-05-21 20:35:23,059 [salt.minion      :1432][INFO    ][8194] Starting a new job with PID 8194
2019-05-21 20:35:23,081 [salt.minion      :1711][INFO    ][8194] Returning information for job: 20190521203523023080
2019-05-21 20:35:24,520 [salt.state       :300 ][INFO    ][7910] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-21 20:35:24,521 [salt.state       :1951][INFO    ][7910] Completed state [maas.deploy_machines] at time 20:35:24.521373 duration_in_ms=13548.895
2019-05-21 20:35:24,526 [salt.minion      :1711][INFO    ][7910] Returning information for job: 20190521203507899919
2019-05-21 20:35:25,099 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521203525084750
2019-05-21 20:35:25,124 [salt.minion      :1432][INFO    ][8223] Starting a new job with PID 8223
2019-05-21 20:35:32,453 [salt.state       :915 ][INFO    ][8223] Loading fresh modules for state activity
2019-05-21 20:35:32,517 [salt.fileclient  :1219][INFO    ][8223] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-21 20:35:32,567 [salt.state       :1780][INFO    ][8223] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:35:32.566985
2019-05-21 20:35:32,567 [salt.state       :1813][INFO    ][8223] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 20:35:32,569 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8223] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 20:35:34,348 [salt.state       :300 ][INFO    ][8223] {'pid': 8234, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 20:35:34,349 [salt.state       :1951][INFO    ][8223] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:35:34.349370 duration_in_ms=1782.386
2019-05-21 20:35:34,351 [salt.state       :1780][INFO    ][8223] Running state [maas.wait_for_machine_status] at time 20:35:34.351484
2019-05-21 20:35:34,351 [salt.state       :1813][INFO    ][8223] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 20:35:34,352 [salt.utils.decorators:613 ][WARNING ][8223] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 20:35:37,124 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.23698711s left)
2019-05-21 20:35:40,188 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203540178778
2019-05-21 20:35:40,210 [salt.minion      :1432][INFO    ][8257] Starting a new job with PID 8257
2019-05-21 20:35:40,231 [salt.minion      :1711][INFO    ][8257] Returning information for job: 20190521203540178778
2019-05-21 20:36:10,205 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.1562202s left)
2019-05-21 20:36:10,259 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203610248114
2019-05-21 20:36:10,283 [salt.minion      :1432][INFO    ][8291] Starting a new job with PID 8291
2019-05-21 20:36:10,305 [salt.minion      :1711][INFO    ][8291] Returning information for job: 20190521203610248114
2019-05-21 20:36:40,387 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203640373600
2019-05-21 20:36:40,409 [salt.minion      :1432][INFO    ][8332] Starting a new job with PID 8332
2019-05-21 20:36:40,429 [salt.minion      :1711][INFO    ][8332] Returning information for job: 20190521203640373600
2019-05-21 20:36:43,184 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.17716217s left)
2019-05-21 20:37:10,449 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203710435015
2019-05-21 20:37:10,481 [salt.minion      :1432][INFO    ][8366] Starting a new job with PID 8366
2019-05-21 20:37:10,504 [salt.minion      :1711][INFO    ][8366] Returning information for job: 20190521203710435015
2019-05-21 20:37:16,232 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.12950015s left)
2019-05-21 20:37:40,552 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203740533604
2019-05-21 20:37:40,582 [salt.minion      :1432][INFO    ][8410] Starting a new job with PID 8410
2019-05-21 20:37:40,608 [salt.minion      :1711][INFO    ][8410] Returning information for job: 20190521203740533604
2019-05-21 20:37:48,939 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.42189312s left)
2019-05-21 20:38:10,627 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203810608765
2019-05-21 20:38:10,656 [salt.minion      :1432][INFO    ][8463] Starting a new job with PID 8463
2019-05-21 20:38:10,678 [salt.minion      :1711][INFO    ][8463] Returning information for job: 20190521203810608765
2019-05-21 20:38:22,141 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.22018099s left)
2019-05-21 20:38:40,731 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203840711634
2019-05-21 20:38:40,762 [salt.minion      :1432][INFO    ][8572] Starting a new job with PID 8572
2019-05-21 20:38:40,788 [salt.minion      :1711][INFO    ][8572] Returning information for job: 20190521203840711634
2019-05-21 20:38:55,151 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.21030211s left)
2019-05-21 20:39:10,823 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203910814918
2019-05-21 20:39:10,852 [salt.minion      :1432][INFO    ][8773] Starting a new job with PID 8773
2019-05-21 20:39:10,878 [salt.minion      :1711][INFO    ][8773] Returning information for job: 20190521203910814918
2019-05-21 20:39:28,035 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.3256321s left)
2019-05-21 20:39:40,908 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521203940892029
2019-05-21 20:39:40,933 [salt.minion      :1432][INFO    ][8920] Starting a new job with PID 8920
2019-05-21 20:39:40,956 [salt.minion      :1711][INFO    ][8920] Returning information for job: 20190521203940892029
2019-05-21 20:40:00,751 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.60952401s left)
2019-05-21 20:40:11,012 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204010996668
2019-05-21 20:40:11,036 [salt.minion      :1432][INFO    ][9109] Starting a new job with PID 9109
2019-05-21 20:40:11,061 [salt.minion      :1711][INFO    ][9109] Returning information for job: 20190521204010996668
2019-05-21 20:40:34,061 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.30020905s left)
2019-05-21 20:40:41,105 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204041096290
2019-05-21 20:40:41,121 [salt.minion      :1432][INFO    ][9208] Starting a new job with PID 9208
2019-05-21 20:40:41,144 [salt.minion      :1711][INFO    ][9208] Returning information for job: 20190521204041096290
2019-05-21 20:41:06,792 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.56909704s left)
2019-05-21 20:41:11,181 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204111171872
2019-05-21 20:41:11,195 [salt.minion      :1432][INFO    ][9499] Starting a new job with PID 9499
2019-05-21 20:41:11,218 [salt.minion      :1711][INFO    ][9499] Returning information for job: 20190521204111171872
2019-05-21 20:41:39,688 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.67273617s left)
2019-05-21 20:41:41,309 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204141293030
2019-05-21 20:41:41,338 [salt.minion      :1432][INFO    ][9625] Starting a new job with PID 9625
2019-05-21 20:41:41,361 [salt.minion      :1711][INFO    ][9625] Returning information for job: 20190521204141293030
2019-05-21 20:42:11,465 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204211453257
2019-05-21 20:42:11,488 [salt.minion      :1432][INFO    ][9893] Starting a new job with PID 9893
2019-05-21 20:42:11,518 [salt.minion      :1711][INFO    ][9893] Returning information for job: 20190521204211453257
2019-05-21 20:42:12,630 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.73121119s left)
2019-05-21 20:42:41,623 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204241606483
2019-05-21 20:42:41,652 [salt.minion      :1432][INFO    ][9993] Starting a new job with PID 9993
2019-05-21 20:42:41,674 [salt.minion      :1711][INFO    ][9993] Returning information for job: 20190521204241606483
2019-05-21 20:42:45,474 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.88658214s left)
2019-05-21 20:43:11,793 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204311779963
2019-05-21 20:43:11,821 [salt.minion      :1432][INFO    ][10203] Starting a new job with PID 10203
2019-05-21 20:43:11,845 [salt.minion      :1711][INFO    ][10203] Returning information for job: 20190521204311779963
2019-05-21 20:43:18,464 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.89676714s left)
2019-05-21 20:43:41,948 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204341935923
2019-05-21 20:43:41,976 [salt.minion      :1432][INFO    ][10468] Starting a new job with PID 10468
2019-05-21 20:43:42,008 [salt.minion      :1711][INFO    ][10468] Returning information for job: 20190521204341935923
2019-05-21 20:43:51,883 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.47768521s left)
2019-05-21 20:44:12,141 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204412129232
2019-05-21 20:44:12,169 [salt.minion      :1432][INFO    ][10667] Starting a new job with PID 10667
2019-05-21 20:44:12,193 [salt.minion      :1711][INFO    ][10667] Returning information for job: 20190521204412129232
2019-05-21 20:44:24,773 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.58837819s left)
2019-05-21 20:44:42,363 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204442351815
2019-05-21 20:44:42,393 [salt.minion      :1432][INFO    ][10764] Starting a new job with PID 10764
2019-05-21 20:44:42,416 [salt.minion      :1711][INFO    ][10764] Returning information for job: 20190521204442351815
2019-05-21 20:44:57,563 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.79791212s left)
2019-05-21 20:45:12,548 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204512531097
2019-05-21 20:45:12,579 [salt.minion      :1432][INFO    ][10904] Starting a new job with PID 10904
2019-05-21 20:45:12,599 [salt.minion      :1711][INFO    ][10904] Returning information for job: 20190521204512531097
2019-05-21 20:45:30,625 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1653.73605204s left)
2019-05-21 20:45:42,682 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204542668175
2019-05-21 20:45:42,711 [salt.minion      :1432][INFO    ][10989] Starting a new job with PID 10989
2019-05-21 20:45:42,738 [salt.minion      :1711][INFO    ][10989] Returning information for job: 20190521204542668175
2019-05-21 20:46:03,452 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1620.90942621s left)
2019-05-21 20:46:12,873 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204612854829
2019-05-21 20:46:12,906 [salt.minion      :1432][INFO    ][11080] Starting a new job with PID 11080
2019-05-21 20:46:12,929 [salt.minion      :1711][INFO    ][11080] Returning information for job: 20190521204612854829
2019-05-21 20:46:36,300 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1588.06143498s left)
2019-05-21 20:46:43,064 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204643048209
2019-05-21 20:46:43,096 [salt.minion      :1432][INFO    ][11146] Starting a new job with PID 11146
2019-05-21 20:46:43,120 [salt.minion      :1711][INFO    ][11146] Returning information for job: 20190521204643048209
2019-05-21 20:47:09,049 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1555.31230807s left)
2019-05-21 20:47:13,255 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204713238993
2019-05-21 20:47:13,287 [salt.minion      :1432][INFO    ][11177] Starting a new job with PID 11177
2019-05-21 20:47:13,312 [salt.minion      :1711][INFO    ][11177] Returning information for job: 20190521204713238993
2019-05-21 20:47:41,938 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1522.42256713s left)
2019-05-21 20:47:43,469 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204743452461
2019-05-21 20:47:43,488 [salt.minion      :1432][INFO    ][11264] Starting a new job with PID 11264
2019-05-21 20:47:43,513 [salt.minion      :1711][INFO    ][11264] Returning information for job: 20190521204743452461
2019-05-21 20:48:13,620 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204813606829
2019-05-21 20:48:13,642 [salt.minion      :1432][INFO    ][11342] Starting a new job with PID 11342
2019-05-21 20:48:13,668 [salt.minion      :1711][INFO    ][11342] Returning information for job: 20190521204813606829
2019-05-21 20:48:15,023 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1489.33765411s left)
2019-05-21 20:48:43,852 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204843837304
2019-05-21 20:48:43,890 [salt.minion      :1432][INFO    ][11451] Starting a new job with PID 11451
2019-05-21 20:48:43,918 [salt.minion      :1711][INFO    ][11451] Returning information for job: 20190521204843837304
2019-05-21 20:48:47,890 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1456.47148705s left)
2019-05-21 20:49:13,876 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204913859732
2019-05-21 20:49:13,907 [salt.minion      :1432][INFO    ][11637] Starting a new job with PID 11637
2019-05-21 20:49:13,931 [salt.minion      :1711][INFO    ][11637] Returning information for job: 20190521204913859732
2019-05-21 20:49:20,781 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1423.58040214s left)
2019-05-21 20:49:43,912 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521204943896363
2019-05-21 20:49:43,939 [salt.minion      :1432][INFO    ][11682] Starting a new job with PID 11682
2019-05-21 20:49:43,974 [salt.minion      :1711][INFO    ][11682] Returning information for job: 20190521204943896363
2019-05-21 20:49:53,705 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1390.65621614s left)
2019-05-21 20:50:13,984 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205013968961
2019-05-21 20:50:14,016 [salt.minion      :1432][INFO    ][11714] Starting a new job with PID 11714
2019-05-21 20:50:14,045 [salt.minion      :1711][INFO    ][11714] Returning information for job: 20190521205013968961
2019-05-21 20:50:26,694 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1357.66691017s left)
2019-05-21 20:50:44,070 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205044052204
2019-05-21 20:50:44,098 [salt.minion      :1432][INFO    ][11762] Starting a new job with PID 11762
2019-05-21 20:50:44,128 [salt.minion      :1711][INFO    ][11762] Returning information for job: 20190521205044052204
2019-05-21 20:50:59,482 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1324.87862515s left)
2019-05-21 20:51:14,120 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205114105268
2019-05-21 20:51:14,149 [salt.minion      :1432][INFO    ][11796] Starting a new job with PID 11796
2019-05-21 20:51:14,176 [salt.minion      :1711][INFO    ][11796] Returning information for job: 20190521205114105268
2019-05-21 20:51:32,407 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1291.95375109s left)
2019-05-21 20:51:44,217 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205144201390
2019-05-21 20:51:44,249 [salt.minion      :1432][INFO    ][11842] Starting a new job with PID 11842
2019-05-21 20:51:44,273 [salt.minion      :1711][INFO    ][11842] Returning information for job: 20190521205144201390
2019-05-21 20:52:05,322 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1259.03862214s left)
2019-05-21 20:52:14,301 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205214287299
2019-05-21 20:52:14,331 [salt.minion      :1432][INFO    ][11881] Starting a new job with PID 11881
2019-05-21 20:52:14,355 [salt.minion      :1711][INFO    ][11881] Returning information for job: 20190521205214287299
2019-05-21 20:52:38,149 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1226.21185017s left)
2019-05-21 20:52:44,415 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205244398248
2019-05-21 20:52:44,437 [salt.minion      :1432][INFO    ][11926] Starting a new job with PID 11926
2019-05-21 20:52:44,471 [salt.minion      :1711][INFO    ][11926] Returning information for job: 20190521205244398248
2019-05-21 20:53:10,895 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1193.465909s left)
2019-05-21 20:53:14,528 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205314513370
2019-05-21 20:53:14,555 [salt.minion      :1432][INFO    ][11960] Starting a new job with PID 11960
2019-05-21 20:53:14,577 [salt.minion      :1711][INFO    ][11960] Returning information for job: 20190521205314513370
2019-05-21 20:53:43,665 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1160.69598317s left)
2019-05-21 20:53:44,650 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205344634782
2019-05-21 20:53:44,677 [salt.minion      :1432][INFO    ][12011] Starting a new job with PID 12011
2019-05-21 20:53:44,705 [salt.minion      :1711][INFO    ][12011] Returning information for job: 20190521205344634782
2019-05-21 20:54:14,792 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205414779310
2019-05-21 20:54:14,816 [salt.minion      :1432][INFO    ][12052] Starting a new job with PID 12052
2019-05-21 20:54:14,839 [salt.minion      :1711][INFO    ][12052] Returning information for job: 20190521205414779310
2019-05-21 20:54:16,535 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1127.82649422s left)
2019-05-21 20:54:44,943 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205444927209
2019-05-21 20:54:44,971 [salt.minion      :1432][INFO    ][12095] Starting a new job with PID 12095
2019-05-21 20:54:45,001 [salt.minion      :1711][INFO    ][12095] Returning information for job: 20190521205444927209
2019-05-21 20:54:49,374 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1094.98674011s left)
2019-05-21 20:55:15,113 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205515103012
2019-05-21 20:55:15,144 [salt.minion      :1432][INFO    ][12130] Starting a new job with PID 12130
2019-05-21 20:55:15,168 [salt.minion      :1711][INFO    ][12130] Returning information for job: 20190521205515103012
2019-05-21 20:55:22,186 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1062.17524099s left)
2019-05-21 20:55:45,288 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205545271949
2019-05-21 20:55:45,317 [salt.minion      :1432][INFO    ][12173] Starting a new job with PID 12173
2019-05-21 20:55:45,342 [salt.minion      :1711][INFO    ][12173] Returning information for job: 20190521205545271949
2019-05-21 20:55:55,048 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1029.31313801s left)
2019-05-21 20:56:15,477 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205615460852
2019-05-21 20:56:15,507 [salt.minion      :1432][INFO    ][12207] Starting a new job with PID 12207
2019-05-21 20:56:15,531 [salt.minion      :1711][INFO    ][12207] Returning information for job: 20190521205615460852
2019-05-21 20:56:27,916 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (996.445378065s left)
2019-05-21 20:56:45,676 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205645658555
2019-05-21 20:56:45,704 [salt.minion      :1432][INFO    ][12252] Starting a new job with PID 12252
2019-05-21 20:56:45,728 [salt.minion      :1711][INFO    ][12252] Returning information for job: 20190521205645658555
2019-05-21 20:57:00,807 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (963.553619146s left)
2019-05-21 20:57:15,700 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205715683403
2019-05-21 20:57:15,728 [salt.minion      :1432][INFO    ][12286] Starting a new job with PID 12286
2019-05-21 20:57:15,754 [salt.minion      :1711][INFO    ][12286] Returning information for job: 20190521205715683403
2019-05-21 20:57:33,569 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (930.79245019s left)
2019-05-21 20:57:45,922 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205745904150
2019-05-21 20:57:45,953 [salt.minion      :1432][INFO    ][12332] Starting a new job with PID 12332
2019-05-21 20:57:45,978 [salt.minion      :1711][INFO    ][12332] Returning information for job: 20190521205745904150
2019-05-21 20:58:06,416 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (897.94543004s left)
2019-05-21 20:58:15,971 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205815954523
2019-05-21 20:58:16,001 [salt.minion      :1432][INFO    ][12364] Starting a new job with PID 12364
2019-05-21 20:58:16,024 [salt.minion      :1711][INFO    ][12364] Returning information for job: 20190521205815954523
2019-05-21 20:58:39,275 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (865.08607316s left)
2019-05-21 20:58:46,016 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205846002075
2019-05-21 20:58:46,045 [salt.minion      :1432][INFO    ][12410] Starting a new job with PID 12410
2019-05-21 20:58:46,068 [salt.minion      :1711][INFO    ][12410] Returning information for job: 20190521205846002075
2019-05-21 20:59:12,058 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (832.303231001s left)
2019-05-21 20:59:16,104 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205916087003
2019-05-21 20:59:16,134 [salt.minion      :1432][INFO    ][12443] Starting a new job with PID 12443
2019-05-21 20:59:16,158 [salt.minion      :1711][INFO    ][12443] Returning information for job: 20190521205916087003
2019-05-21 20:59:44,915 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (799.446645021s left)
2019-05-21 20:59:46,231 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521205946223050
2019-05-21 20:59:46,256 [salt.minion      :1432][INFO    ][12487] Starting a new job with PID 12487
2019-05-21 20:59:46,281 [salt.minion      :1711][INFO    ][12487] Returning information for job: 20190521205946223050
2019-05-21 21:00:16,340 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210016330311
2019-05-21 21:00:16,365 [salt.minion      :1432][INFO    ][12518] Starting a new job with PID 12518
2019-05-21 21:00:16,389 [salt.minion      :1711][INFO    ][12518] Returning information for job: 20190521210016330311
2019-05-21 21:00:17,720 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (766.640821218s left)
2019-05-21 21:00:46,493 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210046477365
2019-05-21 21:00:46,521 [salt.minion      :1432][INFO    ][12566] Starting a new job with PID 12566
2019-05-21 21:00:46,546 [salt.minion      :1711][INFO    ][12566] Returning information for job: 20190521210046477365
2019-05-21 21:00:50,603 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (733.75754714s left)
2019-05-21 21:01:16,640 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210116625573
2019-05-21 21:01:16,672 [salt.minion      :1432][INFO    ][12598] Starting a new job with PID 12598
2019-05-21 21:01:16,698 [salt.minion      :1711][INFO    ][12598] Returning information for job: 20190521210116625573
2019-05-21 21:01:23,387 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (700.973865986s left)
2019-05-21 21:01:46,831 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210146815235
2019-05-21 21:01:46,858 [salt.minion      :1432][INFO    ][12642] Starting a new job with PID 12642
2019-05-21 21:01:46,881 [salt.minion      :1711][INFO    ][12642] Returning information for job: 20190521210146815235
2019-05-21 21:01:56,292 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (668.0692451s left)
2019-05-21 21:02:16,984 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210216967946
2019-05-21 21:02:17,009 [salt.minion      :1432][INFO    ][12679] Starting a new job with PID 12679
2019-05-21 21:02:17,034 [salt.minion      :1711][INFO    ][12679] Returning information for job: 20190521210216967946
2019-05-21 21:02:29,154 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (635.206547022s left)
2019-05-21 21:02:47,015 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210246997010
2019-05-21 21:02:47,040 [salt.minion      :1432][INFO    ][12724] Starting a new job with PID 12724
2019-05-21 21:02:47,063 [salt.minion      :1711][INFO    ][12724] Returning information for job: 20190521210246997010
2019-05-21 21:03:02,110 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (602.25078702s left)
2019-05-21 21:03:17,245 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210317229173
2019-05-21 21:03:17,272 [salt.minion      :1432][INFO    ][12756] Starting a new job with PID 12756
2019-05-21 21:03:17,294 [salt.minion      :1711][INFO    ][12756] Returning information for job: 20190521210317229173
2019-05-21 21:03:35,172 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (569.189135075s left)
2019-05-21 21:03:47,292 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210347275449
2019-05-21 21:03:47,325 [salt.minion      :1432][INFO    ][12938] Starting a new job with PID 12938
2019-05-21 21:03:47,348 [salt.minion      :1711][INFO    ][12938] Returning information for job: 20190521210347275449
2019-05-21 21:04:07,929 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (536.431984186s left)
2019-05-21 21:04:17,335 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210417321229
2019-05-21 21:04:17,359 [salt.minion      :1432][INFO    ][12979] Starting a new job with PID 12979
2019-05-21 21:04:17,383 [salt.minion      :1711][INFO    ][12979] Returning information for job: 20190521210417321229
2019-05-21 21:04:40,786 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (503.575056076s left)
2019-05-21 21:04:47,421 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210447411692
2019-05-21 21:04:47,451 [salt.minion      :1432][INFO    ][13023] Starting a new job with PID 13023
2019-05-21 21:04:47,470 [salt.minion      :1711][INFO    ][13023] Returning information for job: 20190521210447411692
2019-05-21 21:05:13,545 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (470.815931082s left)
2019-05-21 21:05:17,544 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210517536645
2019-05-21 21:05:17,568 [salt.minion      :1432][INFO    ][13059] Starting a new job with PID 13059
2019-05-21 21:05:17,596 [salt.minion      :1711][INFO    ][13059] Returning information for job: 20190521210517536645
2019-05-21 21:05:46,391 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (437.969798088s left)
2019-05-21 21:05:47,652 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210547640467
2019-05-21 21:05:47,676 [salt.minion      :1432][INFO    ][13104] Starting a new job with PID 13104
2019-05-21 21:05:47,702 [salt.minion      :1711][INFO    ][13104] Returning information for job: 20190521210547640467
2019-05-21 21:06:17,755 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210617740870
2019-05-21 21:06:17,779 [salt.minion      :1432][INFO    ][13137] Starting a new job with PID 13137
2019-05-21 21:06:17,803 [salt.minion      :1711][INFO    ][13137] Returning information for job: 20190521210617740870
2019-05-21 21:06:19,281 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (405.079724073s left)
2019-05-21 21:06:47,914 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210647898932
2019-05-21 21:06:47,943 [salt.minion      :1432][INFO    ][13176] Starting a new job with PID 13176
2019-05-21 21:06:47,968 [salt.minion      :1711][INFO    ][13176] Returning information for job: 20190521210647898932
2019-05-21 21:06:52,374 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (371.987057209s left)
2019-05-21 21:07:18,081 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210718068962
2019-05-21 21:07:18,105 [salt.minion      :1432][INFO    ][13208] Starting a new job with PID 13208
2019-05-21 21:07:18,128 [salt.minion      :1711][INFO    ][13208] Returning information for job: 20190521210718068962
2019-05-21 21:07:25,095 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (339.266328096s left)
2019-05-21 21:07:48,256 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210748239085
2019-05-21 21:07:48,285 [salt.minion      :1432][INFO    ][13248] Starting a new job with PID 13248
2019-05-21 21:07:48,307 [salt.minion      :1711][INFO    ][13248] Returning information for job: 20190521210748239085
2019-05-21 21:07:57,833 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (306.528153181s left)
2019-05-21 21:08:18,295 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210818274877
2019-05-21 21:08:18,325 [salt.minion      :1432][INFO    ][13279] Starting a new job with PID 13279
2019-05-21 21:08:18,355 [salt.minion      :1711][INFO    ][13279] Returning information for job: 20190521210818274877
2019-05-21 21:08:30,759 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (273.601563215s left)
2019-05-21 21:08:48,310 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210848295826
2019-05-21 21:08:48,343 [salt.minion      :1432][INFO    ][13323] Starting a new job with PID 13323
2019-05-21 21:08:48,370 [salt.minion      :1711][INFO    ][13323] Returning information for job: 20190521210848295826
2019-05-21 21:09:03,591 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (240.770497084s left)
2019-05-21 21:09:18,376 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210918363689
2019-05-21 21:09:18,404 [salt.minion      :1432][INFO    ][13355] Starting a new job with PID 13355
2019-05-21 21:09:18,430 [salt.minion      :1711][INFO    ][13355] Returning information for job: 20190521210918363689
2019-05-21 21:09:36,400 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (207.961393118s left)
2019-05-21 21:09:48,433 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521210948418837
2019-05-21 21:09:48,463 [salt.minion      :1432][INFO    ][13402] Starting a new job with PID 13402
2019-05-21 21:09:48,500 [salt.minion      :1711][INFO    ][13402] Returning information for job: 20190521210948418837
2019-05-21 21:10:09,133 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (175.22826314s left)
2019-05-21 21:10:18,529 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211018516511
2019-05-21 21:10:18,556 [salt.minion      :1432][INFO    ][13438] Starting a new job with PID 13438
2019-05-21 21:10:18,580 [salt.minion      :1711][INFO    ][13438] Returning information for job: 20190521211018516511
2019-05-21 21:10:41,886 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (142.475239992s left)
2019-05-21 21:10:48,540 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211048533134
2019-05-21 21:10:48,562 [salt.minion      :1432][INFO    ][13484] Starting a new job with PID 13484
2019-05-21 21:10:48,585 [salt.minion      :1711][INFO    ][13484] Returning information for job: 20190521211048533134
2019-05-21 21:11:14,801 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (109.560176134s left)
2019-05-21 21:11:18,658 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211118641487
2019-05-21 21:11:18,689 [salt.minion      :1432][INFO    ][13519] Starting a new job with PID 13519
2019-05-21 21:11:18,721 [salt.minion      :1711][INFO    ][13519] Returning information for job: 20190521211118641487
2019-05-21 21:11:47,641 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (76.7204442024s left)
2019-05-21 21:11:48,837 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211148823291
2019-05-21 21:11:48,865 [salt.minion      :1432][INFO    ][13564] Starting a new job with PID 13564
2019-05-21 21:11:48,885 [salt.minion      :1711][INFO    ][13564] Returning information for job: 20190521211148823291
2019-05-21 21:12:19,002 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211218990210
2019-05-21 21:12:19,025 [salt.minion      :1432][INFO    ][13594] Starting a new job with PID 13594
2019-05-21 21:12:19,055 [salt.minion      :1711][INFO    ][13594] Returning information for job: 20190521211218990210
2019-05-21 21:12:20,454 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (43.9071261883s left)
2019-05-21 21:12:49,209 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211249196558
2019-05-21 21:12:49,239 [salt.minion      :1432][INFO    ][13642] Starting a new job with PID 13642
2019-05-21 21:12:49,265 [salt.minion      :1711][INFO    ][13642] Returning information for job: 20190521211249196558
2019-05-21 21:12:53,560 [salt.loaded.ext.module.maas:1023][INFO    ][8223] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (10.8013300896s left)
2019-05-21 21:13:19,415 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211319399474
2019-05-21 21:13:19,444 [salt.minion      :1432][INFO    ][13674] Starting a new job with PID 13674
2019-05-21 21:13:19,474 [salt.minion      :1711][INFO    ][13674] Returning information for job: 20190521211319399474
2019-05-21 21:13:26,528 [salt.state       :302 ][ERROR   ][8223] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-21 21:13:26,529 [salt.state       :1951][INFO    ][8223] Completed state [maas.wait_for_machine_status] at time 21:13:26.529506 duration_in_ms=2272177.985
2019-05-21 21:13:26,539 [salt.minion      :1711][INFO    ][8223] Returning information for job: 20190521203525084750
2019-05-21 21:13:37,376 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command pillar.get with jid 20190521211337361632
2019-05-21 21:13:37,404 [salt.minion      :1432][INFO    ][13726] Starting a new job with PID 13726
2019-05-21 21:13:37,416 [salt.minion      :1711][INFO    ][13726] Returning information for job: 20190521211337361632
2019-05-21 21:13:37,988 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command service.status with jid 20190521211337973162
2019-05-21 21:13:38,014 [salt.minion      :1432][INFO    ][13731] Starting a new job with PID 13731
2019-05-21 21:13:38,539 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13731] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-21 21:13:38,578 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13731] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-21 21:13:38,599 [salt.minion      :1711][INFO    ][13731] Returning information for job: 20190521211337973162
2019-05-21 21:13:39,220 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521211339204135
2019-05-21 21:13:39,247 [salt.minion      :1432][INFO    ][13743] Starting a new job with PID 13743
2019-05-21 21:13:41,844 [salt.utils.schedule:1377][INFO    ][3001] Running scheduled job: __mine_interval
2019-05-21 21:13:46,663 [salt.state       :915 ][INFO    ][13743] Loading fresh modules for state activity
2019-05-21 21:13:47,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 21:13:47,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 21:13:48,627 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 21:13:48,924 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command 'salt-minion --version' in directory '/root'
2019-05-21 21:13:50,823 [salt.state       :1780][INFO    ][13743] Running state [salt-minion] at time 21:13:50.823817
2019-05-21 21:13:50,824 [salt.state       :1813][INFO    ][13743] Executing state pkg.installed for [salt-minion]
2019-05-21 21:13:50,825 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-21 21:13:50,944 [salt.state       :300 ][INFO    ][13743] All specified packages are already installed
2019-05-21 21:13:50,945 [salt.state       :1951][INFO    ][13743] Completed state [salt-minion] at time 21:13:50.945254 duration_in_ms=121.438
2019-05-21 21:13:50,945 [salt.state       :1780][INFO    ][13743] Running state [salt_minion_dependency_packages] at time 21:13:50.945598
2019-05-21 21:13:50,946 [salt.state       :1813][INFO    ][13743] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-21 21:13:50,955 [salt.state       :300 ][INFO    ][13743] All specified packages are already installed
2019-05-21 21:13:50,955 [salt.state       :1951][INFO    ][13743] Completed state [salt_minion_dependency_packages] at time 21:13:50.955873 duration_in_ms=10.275
2019-05-21 21:13:50,960 [salt.state       :1780][INFO    ][13743] Running state [/etc/salt/minion.d/minion.conf] at time 21:13:50.960549
2019-05-21 21:13:50,960 [salt.state       :1813][INFO    ][13743] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-21 21:13:51,234 [salt.state       :300 ][INFO    ][13743] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-21 21:13:51,234 [salt.state       :1951][INFO    ][13743] Completed state [/etc/salt/minion.d/minion.conf] at time 21:13:51.234745 duration_in_ms=274.196
2019-05-21 21:13:51,235 [salt.state       :1780][INFO    ][13743] Running state [python-netaddr] at time 21:13:51.235006
2019-05-21 21:13:51,235 [salt.state       :1813][INFO    ][13743] Executing state pkg.installed for [python-netaddr]
2019-05-21 21:13:51,245 [salt.state       :300 ][INFO    ][13743] All specified packages are already installed
2019-05-21 21:13:51,245 [salt.state       :1951][INFO    ][13743] Completed state [python-netaddr] at time 21:13:51.245322 duration_in_ms=10.316
2019-05-21 21:13:51,248 [salt.state       :1780][INFO    ][13743] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:13:51.248674
2019-05-21 21:13:51,248 [salt.state       :1813][INFO    ][13743] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-21 21:13:51,268 [salt.state       :300 ][INFO    ][13743] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-21 21:13:51,268 [salt.state       :1951][INFO    ][13743] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:13:51.268757 duration_in_ms=20.082
2019-05-21 21:13:51,270 [salt.state       :1780][INFO    ][13743] Running state [salt-minion] at time 21:13:51.270238
2019-05-21 21:13:51,270 [salt.state       :1813][INFO    ][13743] Executing state service.running for [salt-minion]
2019-05-21 21:13:51,271 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-21 21:13:51,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-21 21:13:51,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-21 21:13:51,359 [salt.state       :300 ][INFO    ][13743] The service salt-minion is already running
2019-05-21 21:13:51,360 [salt.state       :1951][INFO    ][13743] Completed state [salt-minion] at time 21:13:51.360349 duration_in_ms=90.111
2019-05-21 21:13:51,364 [salt.state       :1780][INFO    ][13743] Running state [/etc/salt/grains.d] at time 21:13:51.364034
2019-05-21 21:13:51,364 [salt.state       :1813][INFO    ][13743] Executing state file.directory for [/etc/salt/grains.d]
2019-05-21 21:13:51,367 [salt.state       :300 ][INFO    ][13743] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-21 21:13:51,367 [salt.state       :1951][INFO    ][13743] Completed state [/etc/salt/grains.d] at time 21:13:51.367828 duration_in_ms=3.794
2019-05-21 21:13:51,368 [salt.state       :1780][INFO    ][13743] Running state [/etc/salt/grains] at time 21:13:51.368696
2019-05-21 21:13:51,369 [salt.state       :1813][INFO    ][13743] Executing state file.managed for [/etc/salt/grains]
2019-05-21 21:13:51,369 [salt.state       :300 ][INFO    ][13743] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-21 21:13:51,370 [salt.state       :1951][INFO    ][13743] Completed state [/etc/salt/grains] at time 21:13:51.370039 duration_in_ms=1.343
2019-05-21 21:13:51,370 [salt.state       :1780][INFO    ][13743] Running state [/etc/salt/grains.d/placeholder] at time 21:13:51.370636
2019-05-21 21:13:51,371 [salt.state       :1813][INFO    ][13743] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-21 21:13:51,371 [salt.state       :300 ][INFO    ][13743] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-21 21:13:51,371 [salt.state       :1951][INFO    ][13743] Completed state [/etc/salt/grains.d/placeholder] at time 21:13:51.371861 duration_in_ms=1.225
2019-05-21 21:13:51,372 [salt.state       :1780][INFO    ][13743] Running state [/etc/salt/grains.d/sphinx] at time 21:13:51.372466
2019-05-21 21:13:51,372 [salt.state       :1813][INFO    ][13743] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-21 21:13:51,375 [salt.state       :300 ][INFO    ][13743] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-21 21:13:51,376 [salt.state       :1951][INFO    ][13743] Completed state [/etc/salt/grains.d/sphinx] at time 21:13:51.376094 duration_in_ms=3.628
2019-05-21 21:13:51,378 [salt.state       :1780][INFO    ][13743] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:13:51.378143
2019-05-21 21:13:51,378 [salt.state       :1813][INFO    ][13743] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-21 21:13:51,378 [salt.state       :300 ][INFO    ][13743] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-21 21:13:51,378 [salt.state       :1951][INFO    ][13743] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:13:51.378902 duration_in_ms=0.759
2019-05-21 21:13:51,379 [salt.state       :1780][INFO    ][13743] Running state [/etc/salt/grains.d/dns_records] at time 21:13:51.379369
2019-05-21 21:13:51,379 [salt.state       :1813][INFO    ][13743] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-21 21:13:51,380 [salt.state       :300 ][INFO    ][13743] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-21 21:13:51,380 [salt.state       :1951][INFO    ][13743] Completed state [/etc/salt/grains.d/dns_records] at time 21:13:51.380710 duration_in_ms=1.341
2019-05-21 21:13:51,381 [salt.state       :1780][INFO    ][13743] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:13:51.381623
2019-05-21 21:13:51,381 [salt.state       :1813][INFO    ][13743] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-21 21:13:51,382 [salt.state       :300 ][INFO    ][13743] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-21 21:13:51,382 [salt.state       :1951][INFO    ][13743] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:13:51.382406 duration_in_ms=0.783
2019-05-21 21:13:51,382 [salt.state       :1780][INFO    ][13743] Running state [/etc/salt/grains.d/salt] at time 21:13:51.382861
2019-05-21 21:13:51,383 [salt.state       :1813][INFO    ][13743] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-21 21:13:51,384 [salt.state       :300 ][INFO    ][13743] File /etc/salt/grains.d/salt is in the correct state
2019-05-21 21:13:51,384 [salt.state       :1951][INFO    ][13743] Completed state [/etc/salt/grains.d/salt] at time 21:13:51.384574 duration_in_ms=1.711
2019-05-21 21:13:51,387 [salt.state       :1780][INFO    ][13743] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:13:51.387298
2019-05-21 21:13:51,387 [salt.state       :1813][INFO    ][13743] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-21 21:13:51,387 [salt.state       :300 ][INFO    ][13743] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-21 21:13:51,388 [salt.state       :1951][INFO    ][13743] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:13:51.388101 duration_in_ms=0.804
2019-05-21 21:13:51,390 [salt.state       :1780][INFO    ][13743] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:13:51.390887
2019-05-21 21:13:51,391 [salt.state       :1813][INFO    ][13743] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-21 21:13:51,391 [salt.state       :300 ][INFO    ][13743] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-21 21:13:51,391 [salt.state       :1951][INFO    ][13743] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:13:51.391672 duration_in_ms=0.786
2019-05-21 21:13:51,392 [salt.state       :1780][INFO    ][13743] Running state [mine.update] at time 21:13:51.392327
2019-05-21 21:13:51,392 [salt.state       :1813][INFO    ][13743] Executing state module.wait for [mine.update]
2019-05-21 21:13:51,392 [salt.state       :300 ][INFO    ][13743] No changes made for mine.update
2019-05-21 21:13:51,393 [salt.state       :1951][INFO    ][13743] Completed state [mine.update] at time 21:13:51.393118 duration_in_ms=0.791
2019-05-21 21:13:51,393 [salt.state       :1780][INFO    ][13743] Running state [ca-certificates] at time 21:13:51.393358
2019-05-21 21:13:51,393 [salt.state       :1813][INFO    ][13743] Executing state pkg.installed for [ca-certificates]
2019-05-21 21:13:51,403 [salt.state       :300 ][INFO    ][13743] All specified packages are already installed
2019-05-21 21:13:51,403 [salt.state       :1951][INFO    ][13743] Completed state [ca-certificates] at time 21:13:51.403598 duration_in_ms=10.239
2019-05-21 21:13:51,404 [salt.state       :1780][INFO    ][13743] Running state [update-ca-certificates] at time 21:13:51.404286
2019-05-21 21:13:51,404 [salt.state       :1813][INFO    ][13743] Executing state cmd.wait for [update-ca-certificates]
2019-05-21 21:13:51,404 [salt.state       :300 ][INFO    ][13743] No changes made for update-ca-certificates
2019-05-21 21:13:51,405 [salt.state       :1951][INFO    ][13743] Completed state [update-ca-certificates] at time 21:13:51.405000 duration_in_ms=0.713
2019-05-21 21:13:51,405 [salt.state       :1780][INFO    ][13743] Running state [iptables] at time 21:13:51.405239
2019-05-21 21:13:51,405 [salt.state       :1813][INFO    ][13743] Executing state pkg.installed for [iptables]
2019-05-21 21:13:51,414 [salt.state       :300 ][INFO    ][13743] All specified packages are already installed
2019-05-21 21:13:51,415 [salt.state       :1951][INFO    ][13743] Completed state [iptables] at time 21:13:51.414959 duration_in_ms=9.72
2019-05-21 21:13:51,415 [salt.state       :1780][INFO    ][13743] Running state [iptables-persistent] at time 21:13:51.415199
2019-05-21 21:13:51,415 [salt.state       :1813][INFO    ][13743] Executing state pkg.installed for [iptables-persistent]
2019-05-21 21:13:51,424 [salt.state       :300 ][INFO    ][13743] All specified packages are already installed
2019-05-21 21:13:51,424 [salt.state       :1951][INFO    ][13743] Completed state [iptables-persistent] at time 21:13:51.424670 duration_in_ms=9.471
2019-05-21 21:13:51,425 [salt.state       :1780][INFO    ][13743] Running state [iptables_modules_v4_load] at time 21:13:51.425643
2019-05-21 21:13:51,425 [salt.state       :1813][INFO    ][13743] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-21 21:13:51,426 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command 'lsmod' in directory '/root'
2019-05-21 21:13:51,450 [salt.state       :300 ][INFO    ][13743] Kernel modules iptable_filter, ip_tables are already present
2019-05-21 21:13:51,451 [salt.state       :1951][INFO    ][13743] Completed state [iptables_modules_v4_load] at time 21:13:51.451269 duration_in_ms=25.624
2019-05-21 21:13:51,452 [salt.state       :1780][INFO    ][13743] Running state [/etc/iptables/rules.v4] at time 21:13:51.452464
2019-05-21 21:13:51,453 [salt.state       :1813][INFO    ][13743] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-21 21:13:51,566 [salt.state       :300 ][INFO    ][13743] File /etc/iptables/rules.v4 is in the correct state
2019-05-21 21:13:51,566 [salt.state       :1951][INFO    ][13743] Completed state [/etc/iptables/rules.v4] at time 21:13:51.566779 duration_in_ms=114.316
2019-05-21 21:13:51,567 [salt.state       :1780][INFO    ][13743] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:13:51.567855
2019-05-21 21:13:51,568 [salt.state       :1813][INFO    ][13743] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-21 21:13:51,568 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-21 21:13:51,593 [salt.state       :300 ][INFO    ][13743] onlyif execution failed
2019-05-21 21:13:51,594 [salt.state       :1951][INFO    ][13743] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:13:51.594156 duration_in_ms=26.3
2019-05-21 21:13:51,595 [salt.state       :1780][INFO    ][13743] Running state [netfilter-persistent] at time 21:13:51.595818
2019-05-21 21:13:51,596 [salt.state       :1813][INFO    ][13743] Executing state service.running for [netfilter-persistent]
2019-05-21 21:13:51,597 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-21 21:13:51,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-21 21:13:51,642 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-21 21:13:51,664 [salt.state       :300 ][INFO    ][13743] The service netfilter-persistent is already running
2019-05-21 21:13:51,664 [salt.state       :1951][INFO    ][13743] Completed state [netfilter-persistent] at time 21:13:51.664362 duration_in_ms=68.543
2019-05-21 21:13:51,665 [salt.state       :1780][INFO    ][13743] Running state [iptables_extra.remove_stale_tables] at time 21:13:51.665393
2019-05-21 21:13:51,667 [salt.state       :1813][INFO    ][13743] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-21 21:13:51,668 [salt.state       :300 ][INFO    ][13743] No changes made for iptables_extra.remove_stale_tables
2019-05-21 21:13:51,668 [salt.state       :1951][INFO    ][13743] Completed state [iptables_extra.remove_stale_tables] at time 21:13:51.668568 duration_in_ms=3.175
2019-05-21 21:13:51,668 [salt.state       :1780][INFO    ][13743] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:13:51.668845
2019-05-21 21:13:51,669 [salt.state       :1813][INFO    ][13743] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-21 21:13:51,669 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13743] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-21 21:13:51,691 [salt.state       :300 ][INFO    ][13743] onlyif execution failed
2019-05-21 21:13:51,692 [salt.state       :1951][INFO    ][13743] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:13:51.692457 duration_in_ms=23.611
2019-05-21 21:13:51,694 [salt.state       :1780][INFO    ][13743] Running state [/etc/iptables/rules.v6] at time 21:13:51.694273
2019-05-21 21:13:51,694 [salt.state       :1813][INFO    ][13743] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-21 21:13:51,695 [salt.state       :300 ][INFO    ][13743] File /etc/iptables/rules.v6 is not present
2019-05-21 21:13:51,695 [salt.state       :1951][INFO    ][13743] Completed state [/etc/iptables/rules.v6] at time 21:13:51.695862 duration_in_ms=1.589
2019-05-21 21:13:51,697 [salt.state       :1780][INFO    ][13743] Running state [iptables_extra.flush_all] at time 21:13:51.697045
2019-05-21 21:13:51,697 [salt.state       :1813][INFO    ][13743] Executing state module.wait for [iptables_extra.flush_all]
2019-05-21 21:13:51,701 [salt.state       :300 ][INFO    ][13743] No changes made for iptables_extra.flush_all
2019-05-21 21:13:51,702 [salt.state       :1951][INFO    ][13743] Completed state [iptables_extra.flush_all] at time 21:13:51.702121 duration_in_ms=5.076
2019-05-21 21:13:51,706 [salt.minion      :1711][INFO    ][13743] Returning information for job: 20190521211339204135
2019-05-21 21:13:52,280 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521211352268109
2019-05-21 21:13:52,308 [salt.minion      :1432][INFO    ][13851] Starting a new job with PID 13851
2019-05-21 21:13:53,411 [salt.state       :915 ][INFO    ][13851] Loading fresh modules for state activity
2019-05-21 21:13:54,322 [salt.state       :1780][INFO    ][13851] Running state [maas-rack-controller] at time 21:13:54.322695
2019-05-21 21:13:54,323 [salt.state       :1813][INFO    ][13851] Executing state pkg.installed for [maas-rack-controller]
2019-05-21 21:13:54,328 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13851] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-21 21:13:54,448 [salt.state       :300 ][INFO    ][13851] All specified packages are already installed
2019-05-21 21:13:54,449 [salt.state       :1951][INFO    ][13851] Completed state [maas-rack-controller] at time 21:13:54.449057 duration_in_ms=126.364
2019-05-21 21:13:54,449 [salt.state       :1780][INFO    ][13851] Running state [ipmitool] at time 21:13:54.449416
2019-05-21 21:13:54,449 [salt.state       :1813][INFO    ][13851] Executing state pkg.installed for [ipmitool]
2019-05-21 21:13:54,459 [salt.state       :300 ][INFO    ][13851] All specified packages are already installed
2019-05-21 21:13:54,459 [salt.state       :1951][INFO    ][13851] Completed state [ipmitool] at time 21:13:54.459204 duration_in_ms=9.788
2019-05-21 21:13:54,463 [salt.state       :1780][INFO    ][13851] Running state [/etc/maas/rackd.conf] at time 21:13:54.463290
2019-05-21 21:13:54,463 [salt.state       :1813][INFO    ][13851] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-21 21:13:54,464 [salt.state       :300 ][INFO    ][13851] No changes needed to be made
2019-05-21 21:13:54,464 [salt.state       :1951][INFO    ][13851] Completed state [/etc/maas/rackd.conf] at time 21:13:54.464732 duration_in_ms=1.442
2019-05-21 21:13:54,465 [salt.state       :1780][INFO    ][13851] Running state [/etc/maas/rackd.conf] at time 21:13:54.464973
2019-05-21 21:13:54,465 [salt.state       :1813][INFO    ][13851] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-21 21:13:54,465 [salt.loaded.int.states.file:2298][WARNING ][13851] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-21 21:13:54,466 [salt.state       :300 ][INFO    ][13851] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-21 21:13:54,466 [salt.state       :1951][INFO    ][13851] Completed state [/etc/maas/rackd.conf] at time 21:13:54.466494 duration_in_ms=1.52
2019-05-21 21:13:54,467 [salt.state       :1780][INFO    ][13851] Running state [maas-rackd] at time 21:13:54.467482
2019-05-21 21:13:54,467 [salt.state       :1813][INFO    ][13851] Executing state service.running for [maas-rackd]
2019-05-21 21:13:54,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13851] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-21 21:13:54,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13851] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-21 21:13:54,534 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13851] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-21 21:13:54,556 [salt.state       :300 ][INFO    ][13851] The service maas-rackd is already running
2019-05-21 21:13:54,557 [salt.state       :1951][INFO    ][13851] Completed state [maas-rackd] at time 21:13:54.557006 duration_in_ms=89.522
2019-05-21 21:13:54,561 [salt.minion      :1711][INFO    ][13851] Returning information for job: 20190521211352268109
2019-05-21 21:13:55,136 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521211355123426
2019-05-21 21:13:55,164 [salt.minion      :1432][INFO    ][13877] Starting a new job with PID 13877
2019-05-21 21:13:56,311 [salt.state       :915 ][INFO    ][13877] Loading fresh modules for state activity
2019-05-21 21:13:57,383 [salt.state       :1780][INFO    ][13877] Running state [maas-region-controller] at time 21:13:57.382959
2019-05-21 21:13:57,383 [salt.state       :1813][INFO    ][13877] Executing state pkg.installed for [maas-region-controller]
2019-05-21 21:13:57,384 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-21 21:13:57,504 [salt.state       :300 ][INFO    ][13877] All specified packages are already installed
2019-05-21 21:13:57,505 [salt.state       :1951][INFO    ][13877] Completed state [maas-region-controller] at time 21:13:57.504982 duration_in_ms=122.023
2019-05-21 21:13:57,505 [salt.state       :1780][INFO    ][13877] Running state [python-oauth] at time 21:13:57.505297
2019-05-21 21:13:57,505 [salt.state       :1813][INFO    ][13877] Executing state pkg.installed for [python-oauth]
2019-05-21 21:13:57,514 [salt.state       :300 ][INFO    ][13877] All specified packages are already installed
2019-05-21 21:13:57,515 [salt.state       :1951][INFO    ][13877] Completed state [python-oauth] at time 21:13:57.515012 duration_in_ms=9.714
2019-05-21 21:13:57,519 [salt.state       :1780][INFO    ][13877] Running state [/etc/maas/regiond.conf] at time 21:13:57.518954
2019-05-21 21:13:57,519 [salt.state       :1813][INFO    ][13877] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-21 21:13:57,524 [salt.state       :300 ][INFO    ][13877] No changes needed to be made
2019-05-21 21:13:57,524 [salt.state       :1951][INFO    ][13877] Completed state [/etc/maas/regiond.conf] at time 21:13:57.524435 duration_in_ms=5.481
2019-05-21 21:13:57,524 [salt.state       :1780][INFO    ][13877] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:13:57.524902
2019-05-21 21:13:57,525 [salt.state       :1813][INFO    ][13877] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-21 21:13:57,586 [salt.state       :300 ][INFO    ][13877] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-21 21:13:57,586 [salt.state       :1951][INFO    ][13877] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:13:57.586567 duration_in_ms=61.665
2019-05-21 21:13:57,587 [salt.state       :1780][INFO    ][13877] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:13:57.587104
2019-05-21 21:13:57,587 [salt.state       :1813][INFO    ][13877] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-21 21:13:57,591 [salt.state       :300 ][INFO    ][13877] No changes needed to be made
2019-05-21 21:13:57,592 [salt.state       :1951][INFO    ][13877] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:13:57.592157 duration_in_ms=5.053
2019-05-21 21:13:57,592 [salt.state       :1780][INFO    ][13877] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:13:57.592626
2019-05-21 21:13:57,592 [salt.state       :1813][INFO    ][13877] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-21 21:13:57,596 [salt.state       :300 ][INFO    ][13877] No changes needed to be made
2019-05-21 21:13:57,596 [salt.state       :1951][INFO    ][13877] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:13:57.596406 duration_in_ms=3.78
2019-05-21 21:13:57,596 [salt.state       :1780][INFO    ][13877] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:13:57.596874
2019-05-21 21:13:57,597 [salt.state       :1813][INFO    ][13877] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-21 21:13:57,631 [salt.state       :300 ][INFO    ][13877] No changes needed to be made
2019-05-21 21:13:57,631 [salt.state       :1951][INFO    ][13877] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:13:57.631504 duration_in_ms=34.628
2019-05-21 21:13:57,632 [salt.state       :1780][INFO    ][13877] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:13:57.632057
2019-05-21 21:13:57,632 [salt.state       :1813][INFO    ][13877] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-21 21:13:57,648 [salt.state       :300 ][INFO    ][13877] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-21 21:13:57,649 [salt.state       :1951][INFO    ][13877] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:13:57.648975 duration_in_ms=16.917
2019-05-21 21:13:57,651 [salt.state       :1780][INFO    ][13877] Running state [a2enmod headers] at time 21:13:57.651464
2019-05-21 21:13:57,651 [salt.state       :1813][INFO    ][13877] Executing state cmd.run for [a2enmod headers]
2019-05-21 21:13:57,652 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command 'a2enmod headers' in directory '/root'
2019-05-21 21:13:57,738 [salt.state       :300 ][INFO    ][13877] {'pid': 13897, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-21 21:13:57,739 [salt.state       :1951][INFO    ][13877] Completed state [a2enmod headers] at time 21:13:57.739156 duration_in_ms=87.691
2019-05-21 21:13:57,739 [salt.state       :1780][INFO    ][13877] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:13:57.739770
2019-05-21 21:13:57,740 [salt.state       :1813][INFO    ][13877] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-21 21:13:57,766 [salt.state       :300 ][INFO    ][13877] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-21 21:13:57,767 [salt.state       :1951][INFO    ][13877] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:13:57.766994 duration_in_ms=27.223
2019-05-21 21:13:57,768 [salt.state       :1780][INFO    ][13877] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:13:57.768035
2019-05-21 21:13:57,768 [salt.state       :1813][INFO    ][13877] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-21 21:13:57,827 [salt.state       :300 ][INFO    ][13877] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-21 21:13:57,828 [salt.state       :1951][INFO    ][13877] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:13:57.828299 duration_in_ms=60.263
2019-05-21 21:13:57,829 [salt.state       :1780][INFO    ][13877] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:13:57.829549
2019-05-21 21:13:57,830 [salt.state       :1813][INFO    ][13877] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-21 21:13:57,892 [salt.state       :300 ][INFO    ][13877] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-21 21:13:57,892 [salt.state       :1951][INFO    ][13877] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:13:57.892695 duration_in_ms=63.146
2019-05-21 21:13:57,893 [salt.state       :1780][INFO    ][13877] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:13:57.893368
2019-05-21 21:13:57,893 [salt.state       :1813][INFO    ][13877] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-21 21:13:57,968 [salt.state       :300 ][INFO    ][13877] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-21 21:13:57,968 [salt.state       :1951][INFO    ][13877] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:13:57.968646 duration_in_ms=75.278
2019-05-21 21:13:57,968 [salt.state       :1780][INFO    ][13877] Running state [/root/.pgpass] at time 21:13:57.968907
2019-05-21 21:13:57,969 [salt.state       :1813][INFO    ][13877] Executing state file.managed for [/root/.pgpass]
2019-05-21 21:13:58,024 [salt.state       :300 ][INFO    ][13877] File /root/.pgpass is in the correct state
2019-05-21 21:13:58,024 [salt.state       :1951][INFO    ][13877] Completed state [/root/.pgpass] at time 21:13:58.024234 duration_in_ms=55.326
2019-05-21 21:13:58,031 [salt.state       :1780][INFO    ][13877] Running state [maas-region syncdb --noinput] at time 21:13:58.031343
2019-05-21 21:13:58,031 [salt.state       :1813][INFO    ][13877] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-21 21:13:58,032 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-21 21:14:00,537 [salt.state       :300 ][INFO    ][13877] {'pid': 13910, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, sessions, contenttypes, maasserver, auth, sites, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-21 21:14:00,538 [salt.state       :1951][INFO    ][13877] Completed state [maas-region syncdb --noinput] at time 21:14:00.538223 duration_in_ms=2506.88
2019-05-21 21:14:00,538 [salt.state       :2022][WARNING ][13877] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-21 21:14:00,542 [salt.state       :1780][INFO    ][13877] Running state [maas-regiond] at time 21:14:00.542487
2019-05-21 21:14:00,543 [salt.state       :1813][INFO    ][13877] Executing state service.running for [maas-regiond]
2019-05-21 21:14:00,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-21 21:14:00,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-21 21:14:00,609 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-21 21:14:00,629 [salt.state       :300 ][INFO    ][13877] The service maas-regiond is already running
2019-05-21 21:14:00,632 [salt.state       :1951][INFO    ][13877] Completed state [maas-regiond] at time 21:14:00.631899 duration_in_ms=89.411
2019-05-21 21:14:00,635 [salt.state       :1780][INFO    ][13877] Running state [bind9] at time 21:14:00.635034
2019-05-21 21:14:00,635 [salt.state       :1813][INFO    ][13877] Executing state service.running for [bind9]
2019-05-21 21:14:00,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-21 21:14:00,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-21 21:14:00,685 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-21 21:14:00,707 [salt.state       :300 ][INFO    ][13877] The service bind9 is already running
2019-05-21 21:14:00,708 [salt.state       :1951][INFO    ][13877] Completed state [bind9] at time 21:14:00.708009 duration_in_ms=72.975
2019-05-21 21:14:00,713 [salt.state       :1780][INFO    ][13877] Running state [apache2] at time 21:14:00.713880
2019-05-21 21:14:00,714 [salt.state       :1813][INFO    ][13877] Executing state service.running for [apache2]
2019-05-21 21:14:00,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-21 21:14:00,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-21 21:14:00,764 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-21 21:14:00,789 [salt.state       :300 ][INFO    ][13877] The service apache2 is already running
2019-05-21 21:14:00,790 [salt.state       :1951][INFO    ][13877] Completed state [apache2] at time 21:14:00.790287 duration_in_ms=76.407
2019-05-21 21:14:00,791 [salt.state       :1780][INFO    ][13877] Running state [maasng.wait_for_http_code] at time 21:14:00.791905
2019-05-21 21:14:00,792 [salt.state       :1813][INFO    ][13877] Executing state module.run for [maasng.wait_for_http_code]
2019-05-21 21:14:00,792 [salt.utils.decorators:613 ][WARNING ][13877] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 21:14:00,895 [salt.state       :300 ][INFO    ][13877] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-21 21:14:00,896 [salt.state       :1951][INFO    ][13877] Completed state [maasng.wait_for_http_code] at time 21:14:00.896203 duration_in_ms=104.297
2019-05-21 21:14:00,897 [salt.state       :1780][INFO    ][13877] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:14:00.897440
2019-05-21 21:14:00,897 [salt.state       :1813][INFO    ][13877] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-21 21:14:00,898 [salt.state       :300 ][INFO    ][13877] /var/lib/maas/.setup_admin exists
2019-05-21 21:14:00,898 [salt.state       :1951][INFO    ][13877] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:14:00.898865 duration_in_ms=1.424
2019-05-21 21:14:00,900 [salt.state       :1780][INFO    ][13877] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:14:00.899944
2019-05-21 21:14:00,900 [salt.state       :1813][INFO    ][13877] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 21:14:00,901 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13877] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 21:14:02,604 [salt.state       :300 ][INFO    ][13877] {'pid': 13932, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 21:14:02,605 [salt.state       :1951][INFO    ][13877] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:14:02.605524 duration_in_ms=1705.579
2019-05-21 21:14:02,615 [salt.state       :1780][INFO    ][13877] Running state [maas_region_boot_source_resources_mirror] at time 21:14:02.615692
2019-05-21 21:14:02,616 [salt.state       :1813][INFO    ][13877] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-21 21:14:02,706 [salt.state       :300 ][INFO    ][13877] {'changes': {}}
2019-05-21 21:14:02,706 [salt.state       :1951][INFO    ][13877] Completed state [maas_region_boot_source_resources_mirror] at time 21:14:02.706589 duration_in_ms=90.897
2019-05-21 21:14:02,707 [salt.state       :1780][INFO    ][13877] Running state [maasng.boot_resources_import] at time 21:14:02.707356
2019-05-21 21:14:02,707 [salt.state       :1813][INFO    ][13877] Executing state module.run for [maasng.boot_resources_import]
2019-05-21 21:14:02,708 [salt.utils.decorators:613 ][WARNING ][13877] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 21:14:02,785 [salt.loaded.ext.module.maasng:1600][INFO    ][13877] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-21 21:14:07,835 [salt.loaded.ext.module.maasng:1600][INFO    ][13877] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-21 21:14:10,252 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211410237175
2019-05-21 21:14:10,272 [salt.minion      :1432][INFO    ][13973] Starting a new job with PID 13973
2019-05-21 21:14:10,301 [salt.minion      :1711][INFO    ][13973] Returning information for job: 20190521211410237175
2019-05-21 21:14:12,942 [salt.state       :300 ][INFO    ][13877] {'ret': True}
2019-05-21 21:14:12,943 [salt.state       :1951][INFO    ][13877] Completed state [maasng.boot_resources_import] at time 21:14:12.943365 duration_in_ms=10236.008
2019-05-21 21:14:12,944 [salt.state       :1780][INFO    ][13877] Running state [maas_region_boot_sources_selection_xenial] at time 21:14:12.944777
2019-05-21 21:14:12,945 [salt.state       :1813][INFO    ][13877] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-21 21:14:13,136 [salt.state       :300 ][INFO    ][13877] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-21 21:14:13,136 [salt.state       :1951][INFO    ][13877] Completed state [maas_region_boot_sources_selection_xenial] at time 21:14:13.136450 duration_in_ms=191.672
2019-05-21 21:14:13,138 [salt.state       :1780][INFO    ][13877] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:14:13.138107
2019-05-21 21:14:13,138 [salt.state       :1813][INFO    ][13877] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-21 21:14:13,139 [salt.utils.decorators:613 ][WARNING ][13877] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 21:14:13,139 [salt.loaded.ext.module.maasng:1771][INFO    ][13877] boot-sources sync initiated for ALL Rack's
2019-05-21 21:14:14,198 [salt.state       :300 ][INFO    ][13877] {'ret': True}
2019-05-21 21:14:14,198 [salt.state       :1951][INFO    ][13877] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:14:14.198693 duration_in_ms=1060.587
2019-05-21 21:14:14,200 [salt.state       :1780][INFO    ][13877] Running state [maas.process_maas_config] at time 21:14:14.200813
2019-05-21 21:14:14,201 [salt.state       :1813][INFO    ][13877] Executing state module.run for [maas.process_maas_config]
2019-05-21 21:14:14,202 [salt.utils.decorators:613 ][WARNING ][13877] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 21:14:14,203 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=enable_http_proxy value=True
2019-05-21 21:14:14,262 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=upstream_dns value=8.8.8.8
2019-05-21 21:14:15,748 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=commissioning_distro_series value=xenial
2019-05-21 21:14:15,815 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=default_osystem value=ubuntu
2019-05-21 21:14:15,872 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=active_discovery_interval value=600
2019-05-21 21:14:15,923 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=dnssec_validation value=no
2019-05-21 21:14:15,989 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=maas_name value=mas01
2019-05-21 21:14:16,043 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=network_discovery value=enabled
2019-05-21 21:14:16,134 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=enable_third_party_drivers value=True
2019-05-21 21:14:16,185 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=default_storage_layout value=lvm
2019-05-21 21:14:16,240 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=ntp_external_only value=True
2019-05-21 21:14:16,296 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-21 21:14:16,350 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=default_distro_series value=xenial
2019-05-21 21:14:16,405 [salt.loaded.ext.module.maas:92  ][INFO    ][13877] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-21 21:14:16,520 [salt.state       :300 ][INFO    ][13877] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-21 21:14:16,520 [salt.state       :1951][INFO    ][13877] Completed state [maas.process_maas_config] at time 21:14:16.520853 duration_in_ms=2320.04
2019-05-21 21:14:16,522 [salt.state       :1780][INFO    ][13877] Running state [pxe_admin] at time 21:14:16.521669
2019-05-21 21:14:16,522 [salt.state       :1813][INFO    ][13877] Executing state maasng.fabric_present for [pxe_admin]
2019-05-21 21:14:16,593 [salt.loaded.ext.module.maasng:945 ][INFO    ][13877] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-05-21 21:14:16,658 [salt.loaded.ext.module.maasng:1008][WARNING ][13877] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-21 21:14:16,659 [salt.loaded.ext.module.maasng:1011][WARNING ][13877] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-21 21:14:16,717 [salt.state       :300 ][INFO    ][13877] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-21 21:14:16,719 [salt.state       :1951][INFO    ][13877] Completed state [pxe_admin] at time 21:14:16.719669 duration_in_ms=197.999
2019-05-21 21:14:16,720 [salt.state       :1780][INFO    ][13877] Running state [vlan 0] at time 21:14:16.720065
2019-05-21 21:14:16,720 [salt.state       :1813][INFO    ][13877] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-21 21:14:16,787 [salt.loaded.ext.module.maasng:945 ][INFO    ][13877] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-05-21 21:14:16,892 [salt.loaded.ext.module.maasng:945 ][INFO    ][13877] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-21 21:14:17,131 [salt.loaded.ext.module.maasng:945 ][INFO    ][13877] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-21 21:14:17,213 [salt.state       :300 ][INFO    ][13877] {'new': 'Vlan untagged was updated'}
2019-05-21 21:14:17,213 [salt.state       :1951][INFO    ][13877] Completed state [vlan 0] at time 21:14:17.213872 duration_in_ms=493.806
2019-05-21 21:14:17,215 [salt.state       :1780][INFO    ][13877] Running state [192.168.11.0/24] at time 21:14:17.215122
2019-05-21 21:14:17,215 [salt.state       :1813][INFO    ][13877] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-21 21:14:17,413 [salt.loaded.ext.module.maasng:945 ][INFO    ][13877] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'pxe_admin', u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-21 21:14:17,413 [salt.loaded.ext.module.maasng:1235][WARNING ][13877] Ignoring parameter vlan:0
2019-05-21 21:14:17,483 [salt.state       :300 ][INFO    ][13877] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-21 21:14:17,483 [salt.state       :1951][INFO    ][13877] Completed state [192.168.11.0/24] at time 21:14:17.483736 duration_in_ms=268.613
2019-05-21 21:14:17,484 [salt.state       :1780][INFO    ][13877] Running state [maas_create_iprange_1] at time 21:14:17.484504
2019-05-21 21:14:17,484 [salt.state       :1813][INFO    ][13877] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-21 21:14:17,536 [salt.state       :300 ][INFO    ][13877] Iprange maas_create_iprange_1 already exist.
2019-05-21 21:14:17,536 [salt.state       :1951][INFO    ][13877] Completed state [maas_create_iprange_1] at time 21:14:17.536685 duration_in_ms=52.18
2019-05-21 21:14:17,536 [salt.state       :1780][INFO    ][13877] Running state [vlan 0] at time 21:14:17.536941
2019-05-21 21:14:17,537 [salt.state       :1813][INFO    ][13877] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-21 21:14:17,583 [salt.loaded.ext.module.maasng:945 ][INFO    ][13877] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'pxe_admin', u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-21 21:14:17,682 [salt.loaded.ext.module.maasng:945 ][INFO    ][13877] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-05-21 21:14:17,878 [salt.loaded.ext.module.maasng:945 ][INFO    ][13877] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'pxe_admin', u'relay_vlan': None, u'primary_rack': u'6bwmdb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-21 21:14:17,986 [salt.state       :300 ][INFO    ][13877] {'new': 'Vlan untagged was updated'}
2019-05-21 21:14:17,986 [salt.state       :1951][INFO    ][13877] Completed state [vlan 0] at time 21:14:17.986355 duration_in_ms=449.413
2019-05-21 21:14:17,987 [salt.state       :1780][INFO    ][13877] Running state [opnfv] at time 21:14:17.987261
2019-05-21 21:14:17,987 [salt.state       :1813][INFO    ][13877] Executing state maasng.sshkey_present for [opnfv]
2019-05-21 21:14:18,037 [salt.loaded.ext.module.maasng:1903][INFO    ][13877] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-05-21 21:14:18,041 [salt.state       :300 ][INFO    ][13877] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-21 21:14:18,042 [salt.state       :1951][INFO    ][13877] Completed state [opnfv] at time 21:14:18.042211 duration_in_ms=54.949
2019-05-21 21:14:18,045 [salt.minion      :1711][INFO    ][13877] Returning information for job: 20190521211355123426
2019-05-21 21:14:18,651 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521211418642365
2019-05-21 21:14:18,670 [salt.minion      :1432][INFO    ][14317] Starting a new job with PID 14317
2019-05-21 21:14:26,296 [salt.state       :915 ][INFO    ][14317] Loading fresh modules for state activity
2019-05-21 21:14:26,404 [salt.state       :1780][INFO    ][14317] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:14:26.404897
2019-05-21 21:14:26,405 [salt.state       :1813][INFO    ][14317] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 21:14:26,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14317] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 21:14:28,112 [salt.state       :300 ][INFO    ][14317] {'pid': 14362, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 21:14:28,113 [salt.state       :1951][INFO    ][14317] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:14:28.113087 duration_in_ms=1708.188
2019-05-21 21:14:28,117 [salt.state       :1780][INFO    ][14317] Running state [maas.process_machines] at time 21:14:28.117203
2019-05-21 21:14:28,119 [salt.state       :1813][INFO    ][14317] Executing state module.run for [maas.process_machines]
2019-05-21 21:14:28,123 [salt.utils.decorators:613 ][WARNING ][14317] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 21:14:28,796 [salt.loaded.ext.module.maas:412 ][WARNING ][14317] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 21:14:28,797 [salt.loaded.ext.module.maas:92  ][INFO    ][14317] 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=dhkd8f architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 21:14:30,029 [salt.loaded.ext.module.maas:412 ][WARNING ][14317] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 21:14:30,030 [salt.loaded.ext.module.maas:92  ][INFO    ][14317] 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=td3taq architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 21:14:31,264 [salt.loaded.ext.module.maas:412 ][WARNING ][14317] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 21:14:31,265 [salt.loaded.ext.module.maas:92  ][INFO    ][14317] 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=tpqaap architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 21:14:32,484 [salt.loaded.ext.module.maas:412 ][WARNING ][14317] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 21:14:32,486 [salt.loaded.ext.module.maas:92  ][INFO    ][14317] 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=hs66dh architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 21:14:33,724 [salt.loaded.ext.module.maas:412 ][WARNING ][14317] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-21 21:14:33,724 [salt.loaded.ext.module.maas:92  ][INFO    ][14317] 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=mm8h66 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-21 21:14:33,728 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211433714892
2019-05-21 21:14:33,754 [salt.minion      :1432][INFO    ][14534] Starting a new job with PID 14534
2019-05-21 21:14:33,784 [salt.minion      :1711][INFO    ][14534] Returning information for job: 20190521211433714892
2019-05-21 21:14:34,975 [salt.state       :300 ][INFO    ][14317] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-21 21:14:34,975 [salt.state       :1951][INFO    ][14317] Completed state [maas.process_machines] at time 21:14:34.975759 duration_in_ms=6858.556
2019-05-21 21:14:34,978 [salt.minion      :1711][INFO    ][14317] Returning information for job: 20190521211418642365
2019-05-21 21:15:07,974 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521211507960800
2019-05-21 21:15:08,000 [salt.minion      :1432][INFO    ][14617] Starting a new job with PID 14617
2019-05-21 21:15:15,801 [salt.state       :915 ][INFO    ][14617] Loading fresh modules for state activity
2019-05-21 21:15:15,901 [salt.state       :1780][INFO    ][14617] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:15:15.901353
2019-05-21 21:15:15,902 [salt.state       :1813][INFO    ][14617] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 21:15:15,903 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14617] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 21:15:17,597 [salt.state       :300 ][INFO    ][14617] {'pid': 14626, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 21:15:17,598 [salt.state       :1951][INFO    ][14617] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:15:17.598376 duration_in_ms=1697.022
2019-05-21 21:15:17,608 [salt.state       :1780][INFO    ][14617] Running state [maas.wait_for_machine_status] at time 21:15:17.608058
2019-05-21 21:15:17,608 [salt.state       :1813][INFO    ][14617] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 21:15:17,609 [salt.utils.decorators:613 ][WARNING ][14617] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 21:15:20,432 [salt.state       :300 ][INFO    ][14617] {'ret': True}
2019-05-21 21:15:20,432 [salt.state       :1951][INFO    ][14617] Completed state [maas.wait_for_machine_status] at time 21:15:20.432579 duration_in_ms=2824.52
2019-05-21 21:15:20,435 [salt.minion      :1711][INFO    ][14617] Returning information for job: 20190521211507960800
2019-05-21 21:15:21,091 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521211521024580
2019-05-21 21:15:21,114 [salt.minion      :1432][INFO    ][14670] Starting a new job with PID 14670
2019-05-21 21:15:22,321 [salt.state       :915 ][INFO    ][14670] Loading fresh modules for state activity
2019-05-21 21:15:22,480 [salt.state       :1780][INFO    ][14670] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:15:22.480636
2019-05-21 21:15:22,480 [salt.state       :1813][INFO    ][14670] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 21:15:22,483 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14670] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 21:15:24,252 [salt.state       :300 ][INFO    ][14670] {'pid': 14682, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 21:15:24,253 [salt.state       :1951][INFO    ][14670] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:15:24.253192 duration_in_ms=1772.556
2019-05-21 21:15:24,256 [salt.state       :1780][INFO    ][14670] Running state [maas_machines_storage_cmp002_lvm] at time 21:15:24.256554
2019-05-21 21:15:24,256 [salt.state       :1813][INFO    ][14670] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-21 21:15:24,803 [salt.state       :300 ][INFO    ][14670] Machine cmp002 is not in Ready state.
2019-05-21 21:15:24,803 [salt.state       :1951][INFO    ][14670] Completed state [maas_machines_storage_cmp002_lvm] at time 21:15:24.803762 duration_in_ms=547.206
2019-05-21 21:15:24,804 [salt.state       :1780][INFO    ][14670] Running state [maas_machines_storage_cmp001_lvm] at time 21:15:24.804144
2019-05-21 21:15:24,804 [salt.state       :1813][INFO    ][14670] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-21 21:15:25,366 [salt.state       :300 ][INFO    ][14670] Machine cmp001 is not in Ready state.
2019-05-21 21:15:25,366 [salt.state       :1951][INFO    ][14670] Completed state [maas_machines_storage_cmp001_lvm] at time 21:15:25.366640 duration_in_ms=562.496
2019-05-21 21:15:25,373 [salt.minion      :1711][INFO    ][14670] Returning information for job: 20190521211521024580
2019-05-21 21:15:25,954 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521211525941111
2019-05-21 21:15:25,984 [salt.minion      :1432][INFO    ][14700] Starting a new job with PID 14700
2019-05-21 21:15:27,235 [salt.state       :915 ][INFO    ][14700] Loading fresh modules for state activity
2019-05-21 21:15:27,332 [salt.state       :1780][INFO    ][14700] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:15:27.332730
2019-05-21 21:15:27,333 [salt.state       :1813][INFO    ][14700] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 21:15:27,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14700] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 21:15:29,103 [salt.state       :300 ][INFO    ][14700] {'pid': 14834, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 21:15:29,104 [salt.state       :1951][INFO    ][14700] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:15:29.103902 duration_in_ms=1771.172
2019-05-21 21:15:29,107 [salt.state       :1780][INFO    ][14700] Running state [maas.deploy_machines] at time 21:15:29.107269
2019-05-21 21:15:29,107 [salt.state       :1813][INFO    ][14700] Executing state module.run for [maas.deploy_machines]
2019-05-21 21:15:29,114 [salt.utils.decorators:613 ][WARNING ][14700] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 21:15:29,676 [salt.loaded.ext.module.maas:684 ][INFO    ][14700] deploymachines hwe_kernel=hwe-16.04 system_id=mm8h66 distro_series=xenial
2019-05-21 21:15:32,149 [salt.state       :300 ][INFO    ][14700] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-21 21:15:32,152 [salt.state       :1951][INFO    ][14700] Completed state [maas.deploy_machines] at time 21:15:32.152668 duration_in_ms=3045.398
2019-05-21 21:15:32,156 [salt.minion      :1711][INFO    ][14700] Returning information for job: 20190521211525941111
2019-05-21 21:15:32,719 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command state.apply with jid 20190521211532706526
2019-05-21 21:15:32,745 [salt.minion      :1432][INFO    ][14893] Starting a new job with PID 14893
2019-05-21 21:15:40,060 [salt.state       :915 ][INFO    ][14893] Loading fresh modules for state activity
2019-05-21 21:15:40,161 [salt.state       :1780][INFO    ][14893] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:15:40.160972
2019-05-21 21:15:40,161 [salt.state       :1813][INFO    ][14893] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-21 21:15:40,163 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14893] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-21 21:15:41,913 [salt.state       :300 ][INFO    ][14893] {'pid': 14916, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-21 21:15:41,914 [salt.state       :1951][INFO    ][14893] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:15:41.914662 duration_in_ms=1753.689
2019-05-21 21:15:41,924 [salt.state       :1780][INFO    ][14893] Running state [maas.wait_for_machine_status] at time 21:15:41.923957
2019-05-21 21:15:41,924 [salt.state       :1813][INFO    ][14893] Executing state module.run for [maas.wait_for_machine_status]
2019-05-21 21:15:41,926 [salt.utils.decorators:613 ][WARNING ][14893] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-21 21:15:44,772 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.16703081s left)
2019-05-21 21:15:47,808 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211547791423
2019-05-21 21:15:47,835 [salt.minion      :1432][INFO    ][14932] Starting a new job with PID 14932
2019-05-21 21:15:47,859 [salt.minion      :1711][INFO    ][14932] Returning information for job: 20190521211547791423
2019-05-21 21:16:17,529 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.40953493s left)
2019-05-21 21:16:17,871 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211617862762
2019-05-21 21:16:17,890 [salt.minion      :1432][INFO    ][14967] Starting a new job with PID 14967
2019-05-21 21:16:17,915 [salt.minion      :1711][INFO    ][14967] Returning information for job: 20190521211617862762
2019-05-21 21:16:47,981 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211647972423
2019-05-21 21:16:48,009 [salt.minion      :1432][INFO    ][15005] Starting a new job with PID 15005
2019-05-21 21:16:48,032 [salt.minion      :1711][INFO    ][15005] Returning information for job: 20190521211647972423
2019-05-21 21:16:50,470 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.46877885s left)
2019-05-21 21:17:18,064 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211718050848
2019-05-21 21:17:18,092 [salt.minion      :1432][INFO    ][15038] Starting a new job with PID 15038
2019-05-21 21:17:18,117 [salt.minion      :1711][INFO    ][15038] Returning information for job: 20190521211718050848
2019-05-21 21:17:23,244 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.69481993s left)
2019-05-21 21:17:48,141 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211748125063
2019-05-21 21:17:48,174 [salt.minion      :1432][INFO    ][15081] Starting a new job with PID 15081
2019-05-21 21:17:48,197 [salt.minion      :1711][INFO    ][15081] Returning information for job: 20190521211748125063
2019-05-21 21:17:56,088 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.85025787s left)
2019-05-21 21:18:18,239 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211818223665
2019-05-21 21:18:18,270 [salt.minion      :1432][INFO    ][15113] Starting a new job with PID 15113
2019-05-21 21:18:18,295 [salt.minion      :1711][INFO    ][15113] Returning information for job: 20190521211818223665
2019-05-21 21:18:28,784 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.15518498s left)
2019-05-21 21:18:48,336 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211848319505
2019-05-21 21:18:48,363 [salt.minion      :1432][INFO    ][15160] Starting a new job with PID 15160
2019-05-21 21:18:48,388 [salt.minion      :1711][INFO    ][15160] Returning information for job: 20190521211848319505
2019-05-21 21:19:01,540 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.39889097s left)
2019-05-21 21:19:18,439 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211918421187
2019-05-21 21:19:18,473 [salt.minion      :1432][INFO    ][15194] Starting a new job with PID 15194
2019-05-21 21:19:18,495 [salt.minion      :1711][INFO    ][15194] Returning information for job: 20190521211918421187
2019-05-21 21:19:34,213 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.7260859s left)
2019-05-21 21:19:48,533 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521211948522669
2019-05-21 21:19:48,557 [salt.minion      :1432][INFO    ][15263] Starting a new job with PID 15263
2019-05-21 21:19:48,576 [salt.minion      :1711][INFO    ][15263] Returning information for job: 20190521211948522669
2019-05-21 21:20:07,024 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.91502094s left)
2019-05-21 21:20:18,630 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212018614897
2019-05-21 21:20:18,664 [salt.minion      :1432][INFO    ][15328] Starting a new job with PID 15328
2019-05-21 21:20:18,685 [salt.minion      :1711][INFO    ][15328] Returning information for job: 20190521212018614897
2019-05-21 21:20:39,945 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.99388289s left)
2019-05-21 21:20:48,739 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212048723555
2019-05-21 21:20:48,773 [salt.minion      :1432][INFO    ][15395] Starting a new job with PID 15395
2019-05-21 21:20:48,795 [salt.minion      :1711][INFO    ][15395] Returning information for job: 20190521212048723555
2019-05-21 21:21:12,739 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1919.19960403s left)
2019-05-21 21:21:18,864 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212118851273
2019-05-21 21:21:18,884 [salt.minion      :1432][INFO    ][15544] Starting a new job with PID 15544
2019-05-21 21:21:18,908 [salt.minion      :1711][INFO    ][15544] Returning information for job: 20190521212118851273
2019-05-21 21:21:45,690 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1886.24862599s left)
2019-05-21 21:21:48,981 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212148967959
2019-05-21 21:21:49,009 [salt.minion      :1432][INFO    ][15607] Starting a new job with PID 15607
2019-05-21 21:21:49,029 [salt.minion      :1711][INFO    ][15607] Returning information for job: 20190521212148967959
2019-05-21 21:22:18,648 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1853.29122686s left)
2019-05-21 21:22:19,105 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212219093675
2019-05-21 21:22:19,135 [salt.minion      :1432][INFO    ][15657] Starting a new job with PID 15657
2019-05-21 21:22:19,159 [salt.minion      :1711][INFO    ][15657] Returning information for job: 20190521212219093675
2019-05-21 21:22:49,245 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212249237440
2019-05-21 21:22:49,261 [salt.minion      :1432][INFO    ][15699] Starting a new job with PID 15699
2019-05-21 21:22:49,284 [salt.minion      :1711][INFO    ][15699] Returning information for job: 20190521212249237440
2019-05-21 21:22:51,869 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1820.06943583s left)
2019-05-21 21:23:19,399 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212319382928
2019-05-21 21:23:19,429 [salt.minion      :1432][INFO    ][15799] Starting a new job with PID 15799
2019-05-21 21:23:19,451 [salt.minion      :1711][INFO    ][15799] Returning information for job: 20190521212319382928
2019-05-21 21:23:24,871 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1787.06727004s left)
2019-05-21 21:23:49,547 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212349533356
2019-05-21 21:23:49,571 [salt.minion      :1432][INFO    ][15903] Starting a new job with PID 15903
2019-05-21 21:23:49,596 [salt.minion      :1711][INFO    ][15903] Returning information for job: 20190521212349533356
2019-05-21 21:23:57,534 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1754.40448093s left)
2019-05-21 21:24:19,711 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212419698875
2019-05-21 21:24:19,738 [salt.minion      :1432][INFO    ][16030] Starting a new job with PID 16030
2019-05-21 21:24:19,769 [salt.minion      :1711][INFO    ][16030] Returning information for job: 20190521212419698875
2019-05-21 21:24:30,493 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1721.44551992s left)
2019-05-21 21:24:49,893 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212449878787
2019-05-21 21:24:49,921 [salt.minion      :1432][INFO    ][16067] Starting a new job with PID 16067
2019-05-21 21:24:49,944 [salt.minion      :1711][INFO    ][16067] Returning information for job: 20190521212449878787
2019-05-21 21:25:03,443 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1688.49601603s left)
2019-05-21 21:25:20,053 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212520033393
2019-05-21 21:25:20,081 [salt.minion      :1432][INFO    ][16111] Starting a new job with PID 16111
2019-05-21 21:25:20,105 [salt.minion      :1711][INFO    ][16111] Returning information for job: 20190521212520033393
2019-05-21 21:25:36,343 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1655.59534502s left)
2019-05-21 21:25:50,228 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212550218964
2019-05-21 21:25:50,250 [salt.minion      :1432][INFO    ][16147] Starting a new job with PID 16147
2019-05-21 21:25:50,272 [salt.minion      :1711][INFO    ][16147] Returning information for job: 20190521212550218964
2019-05-21 21:26:09,164 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1622.77489996s left)
2019-05-21 21:26:20,384 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212620374602
2019-05-21 21:26:20,409 [salt.minion      :1432][INFO    ][16198] Starting a new job with PID 16198
2019-05-21 21:26:20,435 [salt.minion      :1711][INFO    ][16198] Returning information for job: 20190521212620374602
2019-05-21 21:26:42,133 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1589.80572391s left)
2019-05-21 21:26:50,564 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212650551589
2019-05-21 21:26:50,588 [salt.minion      :1432][INFO    ][16232] Starting a new job with PID 16232
2019-05-21 21:26:50,623 [salt.minion      :1711][INFO    ][16232] Returning information for job: 20190521212650551589
2019-05-21 21:27:15,162 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1556.77678895s left)
2019-05-21 21:27:20,748 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212720733791
2019-05-21 21:27:20,773 [salt.minion      :1432][INFO    ][16273] Starting a new job with PID 16273
2019-05-21 21:27:20,795 [salt.minion      :1711][INFO    ][16273] Returning information for job: 20190521212720733791
2019-05-21 21:27:48,137 [salt.loaded.ext.module.maas:1023][INFO    ][14893] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1523.80194402s left)
2019-05-21 21:27:50,947 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212750936128
2019-05-21 21:27:50,969 [salt.minion      :1432][INFO    ][16342] Starting a new job with PID 16342
2019-05-21 21:27:50,993 [salt.minion      :1711][INFO    ][16342] Returning information for job: 20190521212750936128
2019-05-21 21:28:21,079 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command saltutil.find_job with jid 20190521212821069121
2019-05-21 21:28:21,103 [salt.minion      :1432][INFO    ][16430] Starting a new job with PID 16430
2019-05-21 21:28:21,126 [salt.minion      :1711][INFO    ][16430] Returning information for job: 20190521212821069121
2019-05-21 21:28:21,196 [salt.state       :300 ][INFO    ][14893] {'ret': True}
2019-05-21 21:28:21,196 [salt.state       :1951][INFO    ][14893] Completed state [maas.wait_for_machine_status] at time 21:28:21.196266 duration_in_ms=759272.307
2019-05-21 21:28:21,200 [salt.minion      :1711][INFO    ][14893] Returning information for job: 20190521211532706526
2019-05-21 22:13:41,843 [salt.utils.schedule:1377][INFO    ][3001] Running scheduled job: __mine_interval
2019-05-21 22:51:09,320 [salt.minion      :1308][INFO    ][3001] User sudo_ubuntu Executing command cp.push_dir with jid 20190521225109307058
2019-05-21 22:51:09,354 [salt.minion      :1432][INFO    ][22470] Starting a new job with PID 22470
