2019-03-01 02:10:13,021 [salt.utils.decorators:613 ][WARNING ][2170] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:10:14,160 [salt.utils.decorators:613 ][WARNING ][2170] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:10:17,966 [salt.loaded.int.states.file:2298][WARNING ][2462] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-01 02:10:33,293 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2782] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-01 02:10:33,319 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2782] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-01 02:10:33,449 [salt.utils.parsers:1051][WARNING ][386] Minion received a SIGTERM. Exiting.
2019-03-01 02:10:34,699 [salt.cli.daemons :293 ][INFO    ][2894] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-03-01 02:10:34,867 [salt.cli.daemons :82  ][INFO    ][2894] Starting up the Salt Minion
2019-03-01 02:10:34,868 [salt.utils.event :1017][INFO    ][2894] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-03-01 02:10:36,187 [salt.minion      :976 ][INFO    ][2894] Creating minion process manager
2019-03-01 02:10:38,357 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][2894] Executing command ['date', '+%z'] in directory '/root'
2019-03-01 02:10:38,385 [salt.utils.schedule:568 ][INFO    ][2894] Updating job settings for scheduled job: __mine_interval
2019-03-01 02:10:38,387 [salt.minion      :1108][INFO    ][2894] Added mine.update to scheduler
2019-03-01 02:10:38,394 [salt.minion      :1975][INFO    ][2894] Minion is starting as user 'root'
2019-03-01 02:10:38,412 [salt.minion      :2336][INFO    ][2894] Minion is ready to receive requests!
2019-03-01 02:10:45,090 [salt.state       :2022][WARNING ][2789] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-01 02:10:45,624 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021045611096
2019-03-01 02:10:45,650 [salt.minion      :1432][INFO    ][3060] Starting a new job with PID 3060
2019-03-01 02:10:45,707 [salt.minion      :1711][INFO    ][3060] Returning information for job: 20190301021045611096
2019-03-01 02:10:48,488 [salt.utils.decorators:613 ][WARNING ][2789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:11:01,569 [salt.utils.decorators:613 ][WARNING ][2789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:11:15,731 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021115722245
2019-03-01 02:11:15,754 [salt.minion      :1432][INFO    ][3944] Starting a new job with PID 3944
2019-03-01 02:11:15,781 [salt.minion      :1711][INFO    ][3944] Returning information for job: 20190301021115722245
2019-03-01 02:11:23,240 [salt.utils.decorators:613 ][WARNING ][2789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:11:24,169 [salt.utils.decorators:613 ][WARNING ][2789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:11:26,288 [salt.loaded.ext.module.maasng:1008][WARNING ][2789] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-03-01 02:11:26,288 [salt.loaded.ext.module.maasng:1011][WARNING ][2789] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-03-01 02:11:26,959 [salt.loaded.ext.module.maasng:1235][WARNING ][2789] Ignoring parameter vlan:0
2019-03-01 02:11:28,940 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301021128931946
2019-03-01 02:11:28,955 [salt.minion      :1432][INFO    ][4196] Starting a new job with PID 4196
2019-03-01 02:11:37,468 [salt.state       :915 ][INFO    ][4196] Loading fresh modules for state activity
2019-03-01 02:11:37,542 [salt.fileclient  :1219][INFO    ][4196] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-03-01 02:11:37,593 [salt.state       :1780][INFO    ][4196] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:37.593347
2019-03-01 02:11:37,593 [salt.state       :1813][INFO    ][4196] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 02:11:37,595 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4196] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 02:11:39,822 [salt.state       :300 ][INFO    ][4196] {'pid': 4232, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 02:11:39,822 [salt.state       :1951][INFO    ][4196] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:39.822762 duration_in_ms=2229.415
2019-03-01 02:11:39,825 [salt.state       :1780][INFO    ][4196] Running state [maas.process_machines] at time 02:11:39.825228
2019-03-01 02:11:39,825 [salt.state       :1813][INFO    ][4196] Executing state module.run for [maas.process_machines]
2019-03-01 02:11:39,826 [salt.utils.decorators:613 ][WARNING ][4196] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:11:39,895 [salt.loaded.ext.module.maas:412 ][WARNING ][4196] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 02:11:39,895 [salt.loaded.ext.module.maas:92  ][INFO    ][4196] 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-03-01 02:11:41,761 [salt.loaded.ext.module.maas:412 ][WARNING ][4196] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 02:11:41,762 [salt.loaded.ext.module.maas:92  ][INFO    ][4196] 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-03-01 02:11:43,199 [salt.loaded.ext.module.maas:412 ][WARNING ][4196] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 02:11:43,200 [salt.loaded.ext.module.maas:92  ][INFO    ][4196] 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-03-01 02:11:43,968 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021143958177
2019-03-01 02:11:43,988 [salt.minion      :1432][INFO    ][4420] Starting a new job with PID 4420
2019-03-01 02:11:44,016 [salt.minion      :1711][INFO    ][4420] Returning information for job: 20190301021143958177
2019-03-01 02:11:44,570 [salt.loaded.ext.module.maas:412 ][WARNING ][4196] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 02:11:44,573 [salt.loaded.ext.module.maas:92  ][INFO    ][4196] 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-03-01 02:11:45,956 [salt.loaded.ext.module.maas:412 ][WARNING ][4196] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 02:11:45,957 [salt.loaded.ext.module.maas:92  ][INFO    ][4196] 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-03-01 02:11:47,444 [salt.state       :300 ][INFO    ][4196] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-01 02:11:47,445 [salt.state       :1951][INFO    ][4196] Completed state [maas.process_machines] at time 02:11:47.445164 duration_in_ms=7619.935
2019-03-01 02:11:47,447 [salt.minion      :1711][INFO    ][4196] Returning information for job: 20190301021128931946
2019-03-01 02:12:32,389 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301021232377993
2019-03-01 02:12:32,409 [salt.minion      :1432][INFO    ][4635] Starting a new job with PID 4635
2019-03-01 02:12:40,472 [salt.state       :915 ][INFO    ][4635] Loading fresh modules for state activity
2019-03-01 02:12:40,532 [salt.fileclient  :1219][INFO    ][4635] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-01 02:12:40,585 [salt.state       :1780][INFO    ][4635] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:40.584822
2019-03-01 02:12:40,585 [salt.state       :1813][INFO    ][4635] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 02:12:40,588 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4635] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 02:12:42,533 [salt.state       :300 ][INFO    ][4635] {'pid': 4642, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 02:12:42,533 [salt.state       :1951][INFO    ][4635] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:42.533808 duration_in_ms=1948.988
2019-03-01 02:12:42,536 [salt.state       :1780][INFO    ][4635] Running state [maas.wait_for_machine_status] at time 02:12:42.536444
2019-03-01 02:12:42,536 [salt.state       :1813][INFO    ][4635] Executing state module.run for [maas.wait_for_machine_status]
2019-03-01 02:12:42,537 [salt.utils.decorators:613 ][WARNING ][4635] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:12:43,200 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.34907413s left)
2019-03-01 02:12:47,465 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021247425028
2019-03-01 02:12:47,481 [salt.minion      :1432][INFO    ][4657] Starting a new job with PID 4657
2019-03-01 02:12:47,508 [salt.minion      :1711][INFO    ][4657] Returning information for job: 20190301021247425028
2019-03-01 02:13:14,028 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.52123809s left)
2019-03-01 02:13:17,479 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021317466143
2019-03-01 02:13:17,497 [salt.minion      :1432][INFO    ][4730] Starting a new job with PID 4730
2019-03-01 02:13:17,525 [salt.minion      :1711][INFO    ][4730] Returning information for job: 20190301021317466143
2019-03-01 02:13:44,717 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.83180714s left)
2019-03-01 02:13:47,564 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021347554564
2019-03-01 02:13:47,578 [salt.minion      :1432][INFO    ][4760] Starting a new job with PID 4760
2019-03-01 02:13:47,605 [salt.minion      :1711][INFO    ][4760] Returning information for job: 20190301021347554564
2019-03-01 02:14:15,625 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.92382503s left)
2019-03-01 02:14:17,782 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021417768534
2019-03-01 02:14:17,804 [salt.minion      :1432][INFO    ][4857] Starting a new job with PID 4857
2019-03-01 02:14:17,832 [salt.minion      :1711][INFO    ][4857] Returning information for job: 20190301021417768534
2019-03-01 02:14:46,643 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.90569806s left)
2019-03-01 02:14:47,851 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021447841766
2019-03-01 02:14:47,865 [salt.minion      :1432][INFO    ][4945] Starting a new job with PID 4945
2019-03-01 02:14:47,892 [salt.minion      :1711][INFO    ][4945] Returning information for job: 20190301021447841766
2019-03-01 02:15:17,720 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.82939816s left)
2019-03-01 02:15:17,952 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021517938575
2019-03-01 02:15:17,977 [salt.minion      :1432][INFO    ][5139] Starting a new job with PID 5139
2019-03-01 02:15:18,008 [salt.minion      :1711][INFO    ][5139] Returning information for job: 20190301021517938575
2019-03-01 02:15:48,070 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021548056587
2019-03-01 02:15:48,097 [salt.minion      :1432][INFO    ][5199] Starting a new job with PID 5199
2019-03-01 02:15:48,129 [salt.minion      :1711][INFO    ][5199] Returning information for job: 20190301021548056587
2019-03-01 02:15:49,586 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.96308398s left)
2019-03-01 02:16:18,266 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021618254073
2019-03-01 02:16:18,295 [salt.minion      :1432][INFO    ][5528] Starting a new job with PID 5528
2019-03-01 02:16:18,335 [salt.minion      :1711][INFO    ][5528] Returning information for job: 20190301021618254073
2019-03-01 02:16:21,429 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.12021899s left)
2019-03-01 02:16:48,406 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021648373595
2019-03-01 02:16:48,458 [salt.minion      :1432][INFO    ][5624] Starting a new job with PID 5624
2019-03-01 02:16:48,494 [salt.minion      :1711][INFO    ][5624] Returning information for job: 20190301021648373595
2019-03-01 02:16:53,014 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.53518319s left)
2019-03-01 02:17:18,638 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021718625046
2019-03-01 02:17:18,662 [salt.minion      :1432][INFO    ][5948] Starting a new job with PID 5948
2019-03-01 02:17:18,692 [salt.minion      :1711][INFO    ][5948] Returning information for job: 20190301021718625046
2019-03-01 02:17:24,978 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.5707531s left)
2019-03-01 02:17:48,755 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021748731817
2019-03-01 02:17:48,800 [salt.minion      :1432][INFO    ][6066] Starting a new job with PID 6066
2019-03-01 02:17:48,854 [salt.minion      :1711][INFO    ][6066] Returning information for job: 20190301021748731817
2019-03-01 02:17:57,352 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.19688702s left)
2019-03-01 02:18:18,770 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021818762260
2019-03-01 02:18:18,786 [salt.minion      :1432][INFO    ][6349] Starting a new job with PID 6349
2019-03-01 02:18:18,823 [salt.minion      :1711][INFO    ][6349] Returning information for job: 20190301021818762260
2019-03-01 02:18:29,736 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1152.81353402s left)
2019-03-01 02:18:48,901 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021848888623
2019-03-01 02:18:48,938 [salt.minion      :1432][INFO    ][6500] Starting a new job with PID 6500
2019-03-01 02:18:48,981 [salt.minion      :1711][INFO    ][6500] Returning information for job: 20190301021848888623
2019-03-01 02:19:02,736 [salt.loaded.ext.module.maas:1023][INFO    ][4635] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1119.81266904s left)
2019-03-01 02:19:19,099 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021919091577
2019-03-01 02:19:19,122 [salt.minion      :1432][INFO    ][6649] Starting a new job with PID 6649
2019-03-01 02:19:19,149 [salt.minion      :1711][INFO    ][6649] Returning information for job: 20190301021919091577
2019-03-01 02:19:35,486 [salt.state       :300 ][INFO    ][4635] {'ret': True}
2019-03-01 02:19:35,486 [salt.state       :1951][INFO    ][4635] Completed state [maas.wait_for_machine_status] at time 02:19:35.486628 duration_in_ms=412950.183
2019-03-01 02:19:35,491 [salt.minion      :1711][INFO    ][4635] Returning information for job: 20190301021232377993
2019-03-01 02:19:36,222 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301021936210671
2019-03-01 02:19:36,242 [salt.minion      :1432][INFO    ][6681] Starting a new job with PID 6681
2019-03-01 02:19:44,797 [salt.state       :915 ][INFO    ][6681] Loading fresh modules for state activity
2019-03-01 02:19:44,863 [salt.fileclient  :1219][INFO    ][6681] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-01 02:19:44,969 [salt.state       :1780][INFO    ][6681] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:44.969821
2019-03-01 02:19:44,970 [salt.state       :1813][INFO    ][6681] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 02:19:44,972 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6681] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 02:19:47,392 [salt.state       :300 ][INFO    ][6681] {'pid': 6689, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 02:19:47,393 [salt.state       :1951][INFO    ][6681] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:47.393235 duration_in_ms=2423.413
2019-03-01 02:19:47,395 [salt.state       :1780][INFO    ][6681] Running state [maas_machines_storage_cmp002_lvm] at time 02:19:47.395894
2019-03-01 02:19:47,396 [salt.state       :1813][INFO    ][6681] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-01 02:19:48,577 [salt.loaded.ext.module.maasng:610 ][INFO    ][6681] dxgmr4
2019-03-01 02:19:48,578 [salt.loaded.ext.module.maasng:626 ][INFO    ][6681] sda
2019-03-01 02:19:49,375 [salt.loaded.ext.module.maasng:361 ][INFO    ][6681] dxgmr4
2019-03-01 02:19:49,464 [salt.loaded.ext.module.maasng:367 ][INFO    ][6681] [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'uuid': None, u'id': 1, u'system_id': u'dxgmr4', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/blockdevices/1/', u'type': u'physical', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'6c99014d-679c-4025-b605-5e7bd2485f94', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dxgmr4', u'filesystem': {u'mount_options': None, u'uuid': u'57073db8-d25b-43b2-b14e-7919cf74b780', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}]}, {u'size': 800097042432, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'uuid': u'a70ead4b-2ae5-454e-a717-d206a57d7591', u'id': 3, u'system_id': u'dxgmr4', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'096c9b3c-2b37-4ef0-8557-4a78ffb8f0fd', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/blockdevices/3/', u'type': u'virtual', u'serial': None, u'partitions': []}]
2019-03-01 02:19:49,465 [salt.loaded.ext.module.maasng:632 ][INFO    ][6681] vgroot
2019-03-01 02:19:49,465 [salt.loaded.ext.module.maasng:635 ][INFO    ][6681] lvroot
2019-03-01 02:19:49,465 [salt.loaded.ext.module.maasng:639 ][INFO    ][6681] 107374182400
2019-03-01 02:19:50,099 [salt.loaded.ext.module.maasng:645 ][INFO    ][6681] {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'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'dxgmr4', u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/blockdevices/1/', 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'partitions': [{u'uuid': u'1624dcd0-16d2-417b-83f8-db66a9f7d4b7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dxgmr4', u'device_id': 1, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'c9bf2a50-cd9d-4e44-ab58-48afd0e0d98e', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/blockdevices/1/partition/6'}]}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/dxgmr4/', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'dxgmr4', u'power_state': u'off', 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'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'725270bf-3f9f-47d1-b804-e26dfe9b68d3', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'dxgmr4', u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/blockdevices/11/', u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'a79d77cd-52af-49a5-b3c7-79a5cddbb5e6', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'partitions': []}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dxgmr4', u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'id': 1, u'partitions': [{u'uuid': u'1624dcd0-16d2-417b-83f8-db66a9f7d4b7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dxgmr4', u'device_id': 1, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'c9bf2a50-cd9d-4e44-ab58-48afd0e0d98e', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/blockdevices/1/partition/6'}]}, {u'model': None, u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'725270bf-3f9f-47d1-b804-e26dfe9b68d3', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dxgmr4', u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/blockdevices/11/', u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'a79d77cd-52af-49a5-b3c7-79a5cddbb5e6', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 11, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'dxgmr4', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'dxgmr4', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'dxgmr4', u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/interfaces/14/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'dxgmr4', u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/interfaces/15/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'dxgmr4', u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/interfaces/12/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'system_id': u'dxgmr4', u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/interfaces/13/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'dxgmr4', u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/interfaces/16/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'dxgmr4', u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/blockdevices/1/', 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'partitions': [{u'uuid': u'1624dcd0-16d2-417b-83f8-db66a9f7d4b7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dxgmr4', u'device_id': 1, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'c9bf2a50-cd9d-4e44-ab58-48afd0e0d98e', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/blockdevices/1/partition/6'}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'dxgmr4', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/dxgmr4/interfaces/5/'}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-03-01 02:19:50,101 [salt.state       :300 ][INFO    ][6681] {'new': {'storage_layout': 'lvm'}}
2019-03-01 02:19:50,101 [salt.state       :1951][INFO    ][6681] Completed state [maas_machines_storage_cmp002_lvm] at time 02:19:50.101611 duration_in_ms=2705.716
2019-03-01 02:19:50,102 [salt.state       :1780][INFO    ][6681] Running state [maas_machines_storage_cmp001_lvm] at time 02:19:50.101995
2019-03-01 02:19:50,102 [salt.state       :1813][INFO    ][6681] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-01 02:19:51,253 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301021951246431
2019-03-01 02:19:51,276 [salt.minion      :1432][INFO    ][6763] Starting a new job with PID 6763
2019-03-01 02:19:51,325 [salt.minion      :1711][INFO    ][6763] Returning information for job: 20190301021951246431
2019-03-01 02:19:51,404 [salt.loaded.ext.module.maasng:610 ][INFO    ][6681] kmbppf
2019-03-01 02:19:51,404 [salt.loaded.ext.module.maasng:626 ][INFO    ][6681] sda
2019-03-01 02:19:51,926 [salt.loaded.ext.module.maasng:361 ][INFO    ][6681] kmbppf
2019-03-01 02:19:52,016 [salt.loaded.ext.module.maasng:367 ][INFO    ][6681] [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'kmbppf', u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'248ba90d-6a73-433c-9eb3-af264c9642fa', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kmbppf', u'device_id': 2, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'b8cfdda0-a6b1-416a-bb2e-73a3f4efd18f', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/blockdevices/2/partition/2'}]}, {u'model': None, u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'0b94c5c0-e743-4963-bcd7-76080e976c5e', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kmbppf', u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/blockdevices/4/', u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'59d337d4-84a6-4f0b-9982-54962f6011b3', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 800097042432, u'type': u'virtual', u'id': 4, u'partitions': []}]
2019-03-01 02:19:52,017 [salt.loaded.ext.module.maasng:632 ][INFO    ][6681] vgroot
2019-03-01 02:19:52,017 [salt.loaded.ext.module.maasng:635 ][INFO    ][6681] lvroot
2019-03-01 02:19:52,017 [salt.loaded.ext.module.maasng:639 ][INFO    ][6681] 107374182400
2019-03-01 02:19:52,552 [salt.loaded.ext.module.maasng:645 ][INFO    ][6681] {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'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/blockdevices/2/', u'type': u'physical', u'id': 2, u'filesystem': None, u'partitions': [{u'uuid': u'd9cb76d1-3bb5-453c-8f48-073a0971641c', u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kmbppf', u'filesystem': {u'uuid': u'5992879d-9492-4052-8354-3e8bc69cd6fb', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'kmbppf', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'tags': [u'ssd'], u'uuid': None}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'kmbppf', 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'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'filesystem': None, u'partitions': [{u'uuid': u'd9cb76d1-3bb5-453c-8f48-073a0971641c', u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kmbppf', u'filesystem': {u'uuid': u'5992879d-9492-4052-8354-3e8bc69cd6fb', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'kmbppf', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'uuid': u'c26c5ab5-30e0-4082-a3d8-378b848e2fa5', u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 12, u'filesystem': {u'uuid': u'1458295e-4fd9-49a7-9360-7bbca6f2594a', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kmbppf', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'kmbppf', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'children': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'kmbppf', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/interfaces/6/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'kmbppf', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/interfaces/17/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'kmbppf', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'kmbppf', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.43'}], u'children': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'kmbppf', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/interfaces/20/'}, {u'name': u'ens2f0', u'links': [{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'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'kmbppf', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/interfaces/21/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/blockdevices/2/', u'type': u'physical', u'id': 2, u'filesystem': None, u'partitions': [{u'uuid': u'd9cb76d1-3bb5-453c-8f48-073a0971641c', u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kmbppf', u'filesystem': {u'uuid': u'5992879d-9492-4052-8354-3e8bc69cd6fb', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 2}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'kmbppf', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'tags': [u'ssd'], u'uuid': None}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/blockdevices/12/', u'type': u'virtual', u'id': 12, u'filesystem': {u'uuid': u'1458295e-4fd9-49a7-9360-7bbca6f2594a', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kmbppf', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'tags': [], u'uuid': u'c26c5ab5-30e0-4082-a3d8-378b848e2fa5'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, 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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'children': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'kmbppf', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/kmbppf/interfaces/6/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/kmbppf/', u'distro_series': u'', u'memory_test_status': -1}
2019-03-01 02:19:52,554 [salt.state       :300 ][INFO    ][6681] {'new': {'storage_layout': 'lvm'}}
2019-03-01 02:19:52,554 [salt.state       :1951][INFO    ][6681] Completed state [maas_machines_storage_cmp001_lvm] at time 02:19:52.554263 duration_in_ms=2452.268
2019-03-01 02:19:52,557 [salt.minion      :1711][INFO    ][6681] Returning information for job: 20190301021936210671
2019-03-01 02:19:53,275 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301021953264239
2019-03-01 02:19:53,299 [salt.minion      :1432][INFO    ][6774] Starting a new job with PID 6774
2019-03-01 02:19:54,185 [salt.state       :915 ][INFO    ][6774] Loading fresh modules for state activity
2019-03-01 02:19:54,233 [salt.fileclient  :1219][INFO    ][6774] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-01 02:19:54,276 [salt.state       :1780][INFO    ][6774] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:54.276224
2019-03-01 02:19:54,276 [salt.state       :1813][INFO    ][6774] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 02:19:54,278 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6774] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 02:19:56,433 [salt.state       :300 ][INFO    ][6774] {'pid': 6781, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 02:19:56,434 [salt.state       :1951][INFO    ][6774] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:19:56.434405 duration_in_ms=2158.182
2019-03-01 02:19:56,436 [salt.state       :1780][INFO    ][6774] Running state [maas.deploy_machines] at time 02:19:56.436840
2019-03-01 02:19:56,437 [salt.state       :1813][INFO    ][6774] Executing state module.run for [maas.deploy_machines]
2019-03-01 02:19:56,437 [salt.utils.decorators:613 ][WARNING ][6774] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:19:56,986 [salt.loaded.ext.module.maas:684 ][INFO    ][6774] deploymachines hwe_kernel=ga-16.04 system_id=dxgmr4 distro_series=xenial
2019-03-01 02:19:59,270 [salt.loaded.ext.module.maas:684 ][INFO    ][6774] deploymachines hwe_kernel=ga-16.04 system_id=kmbppf distro_series=xenial
2019-03-01 02:20:01,664 [salt.loaded.ext.module.maas:684 ][INFO    ][6774] deploymachines hwe_kernel=ga-16.04 system_id=gaaxag distro_series=xenial
2019-03-01 02:20:04,031 [salt.loaded.ext.module.maas:684 ][INFO    ][6774] deploymachines hwe_kernel=ga-16.04 system_id=xmyhdx distro_series=xenial
2019-03-01 02:20:06,145 [salt.loaded.ext.module.maas:684 ][INFO    ][6774] deploymachines hwe_kernel=ga-16.04 system_id=mgy7tc distro_series=xenial
2019-03-01 02:20:08,322 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022008309591
2019-03-01 02:20:08,346 [salt.minion      :1432][INFO    ][7055] Starting a new job with PID 7055
2019-03-01 02:20:08,396 [salt.minion      :1711][INFO    ][7055] Returning information for job: 20190301022008309591
2019-03-01 02:20:08,507 [salt.state       :300 ][INFO    ][6774] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-01 02:20:08,507 [salt.state       :1951][INFO    ][6774] Completed state [maas.deploy_machines] at time 02:20:08.507682 duration_in_ms=12070.84
2019-03-01 02:20:08,515 [salt.minion      :1711][INFO    ][6774] Returning information for job: 20190301021953264239
2019-03-01 02:20:09,249 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301022009235758
2019-03-01 02:20:09,270 [salt.minion      :1432][INFO    ][7074] Starting a new job with PID 7074
2019-03-01 02:20:17,849 [salt.state       :915 ][INFO    ][7074] Loading fresh modules for state activity
2019-03-01 02:20:17,911 [salt.fileclient  :1219][INFO    ][7074] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-01 02:20:17,960 [salt.state       :1780][INFO    ][7074] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:20:17.960328
2019-03-01 02:20:17,960 [salt.state       :1813][INFO    ][7074] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 02:20:17,962 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7074] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 02:20:20,084 [salt.state       :300 ][INFO    ][7074] {'pid': 7089, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 02:20:20,085 [salt.state       :1951][INFO    ][7074] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:20:20.085025 duration_in_ms=2124.697
2019-03-01 02:20:20,086 [salt.state       :1780][INFO    ][7074] Running state [maas.wait_for_machine_status] at time 02:20:20.086635
2019-03-01 02:20:20,087 [salt.state       :1813][INFO    ][7074] Executing state module.run for [maas.wait_for_machine_status]
2019-03-01 02:20:20,088 [salt.utils.decorators:613 ][WARNING ][7074] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:20:22,942 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.15826416s left)
2019-03-01 02:20:24,282 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022024272173
2019-03-01 02:20:24,299 [salt.minion      :1432][INFO    ][7106] Starting a new job with PID 7106
2019-03-01 02:20:24,330 [salt.minion      :1711][INFO    ][7106] Returning information for job: 20190301022024272173
2019-03-01 02:20:54,405 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022054396408
2019-03-01 02:20:54,420 [salt.minion      :1432][INFO    ][7194] Starting a new job with PID 7194
2019-03-01 02:20:54,451 [salt.minion      :1711][INFO    ][7194] Returning information for job: 20190301022054396408
2019-03-01 02:20:55,855 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.24435306s left)
2019-03-01 02:21:24,528 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022124513252
2019-03-01 02:21:24,554 [salt.minion      :1432][INFO    ][7226] Starting a new job with PID 7226
2019-03-01 02:21:24,583 [salt.minion      :1711][INFO    ][7226] Returning information for job: 20190301022124513252
2019-03-01 02:21:28,706 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.39402199s left)
2019-03-01 02:21:54,678 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022154667469
2019-03-01 02:21:54,697 [salt.minion      :1432][INFO    ][7292] Starting a new job with PID 7292
2019-03-01 02:21:54,729 [salt.minion      :1711][INFO    ][7292] Returning information for job: 20190301022154667469
2019-03-01 02:22:01,630 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.46975899s left)
2019-03-01 02:22:24,828 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022224818814
2019-03-01 02:22:24,851 [salt.minion      :1432][INFO    ][7324] Starting a new job with PID 7324
2019-03-01 02:22:24,877 [salt.minion      :1711][INFO    ][7324] Returning information for job: 20190301022224818814
2019-03-01 02:22:34,427 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.67256212s left)
2019-03-01 02:22:54,971 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022254956160
2019-03-01 02:22:55,005 [salt.minion      :1432][INFO    ][7440] Starting a new job with PID 7440
2019-03-01 02:22:55,045 [salt.minion      :1711][INFO    ][7440] Returning information for job: 20190301022254956160
2019-03-01 02:23:07,237 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.86300707s left)
2019-03-01 02:23:25,140 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022325128799
2019-03-01 02:23:25,165 [salt.minion      :1432][INFO    ][7503] Starting a new job with PID 7503
2019-03-01 02:23:25,195 [salt.minion      :1711][INFO    ][7503] Returning information for job: 20190301022325128799
2019-03-01 02:23:40,098 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2050.00150514s left)
2019-03-01 02:23:55,316 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022355307021
2019-03-01 02:23:55,331 [salt.minion      :1432][INFO    ][7810] Starting a new job with PID 7810
2019-03-01 02:23:55,362 [salt.minion      :1711][INFO    ][7810] Returning information for job: 20190301022355307021
2019-03-01 02:24:12,887 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2017.21237302s left)
2019-03-01 02:24:25,451 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022425440117
2019-03-01 02:24:25,470 [salt.minion      :1432][INFO    ][7881] Starting a new job with PID 7881
2019-03-01 02:24:25,509 [salt.minion      :1711][INFO    ][7881] Returning information for job: 20190301022425440117
2019-03-01 02:24:46,117 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.98240113s left)
2019-03-01 02:24:55,538 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022455529036
2019-03-01 02:24:55,556 [salt.minion      :1432][INFO    ][8232] Starting a new job with PID 8232
2019-03-01 02:24:55,583 [salt.minion      :1711][INFO    ][8232] Returning information for job: 20190301022455529036
2019-03-01 02:25:18,845 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1951.25486016s left)
2019-03-01 02:25:25,686 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022525675494
2019-03-01 02:25:25,710 [salt.minion      :1432][INFO    ][8288] Starting a new job with PID 8288
2019-03-01 02:25:25,739 [salt.minion      :1711][INFO    ][8288] Returning information for job: 20190301022525675494
2019-03-01 02:25:53,256 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.84344506s left)
2019-03-01 02:25:55,795 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022555784059
2019-03-01 02:25:55,819 [salt.minion      :1432][INFO    ][8649] Starting a new job with PID 8649
2019-03-01 02:25:55,859 [salt.minion      :1711][INFO    ][8649] Returning information for job: 20190301022555784059
2019-03-01 02:26:26,023 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022626011380
2019-03-01 02:26:26,046 [salt.minion      :1432][INFO    ][8688] Starting a new job with PID 8688
2019-03-01 02:26:26,077 [salt.minion      :1711][INFO    ][8688] Returning information for job: 20190301022626011380
2019-03-01 02:26:26,237 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.86264205s left)
2019-03-01 02:26:56,161 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022656150680
2019-03-01 02:26:56,179 [salt.minion      :1432][INFO    ][8928] Starting a new job with PID 8928
2019-03-01 02:26:56,205 [salt.minion      :1711][INFO    ][8928] Returning information for job: 20190301022656150680
2019-03-01 02:26:59,081 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.01874995s left)
2019-03-01 02:27:26,233 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022726221807
2019-03-01 02:27:26,252 [salt.minion      :1432][INFO    ][8969] Starting a new job with PID 8969
2019-03-01 02:27:26,290 [salt.minion      :1711][INFO    ][8969] Returning information for job: 20190301022726221807
2019-03-01 02:27:32,174 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.92613316s left)
2019-03-01 02:27:56,426 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022756417156
2019-03-01 02:27:56,446 [salt.minion      :1432][INFO    ][9231] Starting a new job with PID 9231
2019-03-01 02:27:56,473 [salt.minion      :1711][INFO    ][9231] Returning information for job: 20190301022756417156
2019-03-01 02:28:05,101 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.99909902s left)
2019-03-01 02:28:26,569 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022826558653
2019-03-01 02:28:26,589 [salt.minion      :1432][INFO    ][9266] Starting a new job with PID 9266
2019-03-01 02:28:26,629 [salt.minion      :1711][INFO    ][9266] Returning information for job: 20190301022826558653
2019-03-01 02:28:37,896 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.20342207s left)
2019-03-01 02:28:56,675 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022856665841
2019-03-01 02:28:56,702 [salt.minion      :1432][INFO    ][9523] Starting a new job with PID 9523
2019-03-01 02:28:56,734 [salt.minion      :1711][INFO    ][9523] Returning information for job: 20190301022856665841
2019-03-01 02:29:10,789 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1719.31091309s left)
2019-03-01 02:29:26,899 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022926886301
2019-03-01 02:29:26,918 [salt.minion      :1432][INFO    ][9544] Starting a new job with PID 9544
2019-03-01 02:29:26,951 [salt.minion      :1711][INFO    ][9544] Returning information for job: 20190301022926886301
2019-03-01 02:29:43,968 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.13155603s left)
2019-03-01 02:29:57,106 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301022957096933
2019-03-01 02:29:57,129 [salt.minion      :1432][INFO    ][9645] Starting a new job with PID 9645
2019-03-01 02:29:57,162 [salt.minion      :1711][INFO    ][9645] Returning information for job: 20190301022957096933
2019-03-01 02:30:17,373 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.72638798s left)
2019-03-01 02:30:27,325 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023027317659
2019-03-01 02:30:27,350 [salt.minion      :1432][INFO    ][9718] Starting a new job with PID 9718
2019-03-01 02:30:27,396 [salt.minion      :1711][INFO    ][9718] Returning information for job: 20190301023027317659
2019-03-01 02:30:53,001 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1617.09891796s left)
2019-03-01 02:30:57,487 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023057474953
2019-03-01 02:30:57,514 [salt.minion      :1432][INFO    ][10186] Starting a new job with PID 10186
2019-03-01 02:30:57,554 [salt.minion      :1711][INFO    ][10186] Returning information for job: 20190301023057474953
2019-03-01 02:31:26,822 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1583.27801514s left)
2019-03-01 02:31:27,565 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023127549019
2019-03-01 02:31:27,608 [salt.minion      :1432][INFO    ][10209] Starting a new job with PID 10209
2019-03-01 02:31:27,656 [salt.minion      :1711][INFO    ][10209] Returning information for job: 20190301023127549019
2019-03-01 02:31:57,709 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023157700505
2019-03-01 02:31:57,731 [salt.minion      :1432][INFO    ][10288] Starting a new job with PID 10288
2019-03-01 02:31:57,773 [salt.minion      :1711][INFO    ][10288] Returning information for job: 20190301023157700505
2019-03-01 02:31:59,602 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1550.49808812s left)
2019-03-01 02:32:27,834 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023227825336
2019-03-01 02:32:27,851 [salt.minion      :1432][INFO    ][10344] Starting a new job with PID 10344
2019-03-01 02:32:27,882 [salt.minion      :1711][INFO    ][10344] Returning information for job: 20190301023227825336
2019-03-01 02:32:32,408 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1517.69149303s left)
2019-03-01 02:32:58,017 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023258006385
2019-03-01 02:32:58,034 [salt.minion      :1432][INFO    ][10584] Starting a new job with PID 10584
2019-03-01 02:32:58,070 [salt.minion      :1711][INFO    ][10584] Returning information for job: 20190301023258006385
2019-03-01 02:33:05,372 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1484.72754407s left)
2019-03-01 02:33:28,064 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023328049113
2019-03-01 02:33:28,087 [salt.minion      :1432][INFO    ][10629] Starting a new job with PID 10629
2019-03-01 02:33:28,116 [salt.minion      :1711][INFO    ][10629] Returning information for job: 20190301023328049113
2019-03-01 02:33:38,173 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1451.92672801s left)
2019-03-01 02:33:58,086 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023358077224
2019-03-01 02:33:58,102 [salt.minion      :1432][INFO    ][10825] Starting a new job with PID 10825
2019-03-01 02:33:58,135 [salt.minion      :1711][INFO    ][10825] Returning information for job: 20190301023358077224
2019-03-01 02:34:11,168 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1418.93169498s left)
2019-03-01 02:34:28,182 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023428162650
2019-03-01 02:34:28,199 [salt.minion      :1432][INFO    ][10845] Starting a new job with PID 10845
2019-03-01 02:34:28,231 [salt.minion      :1711][INFO    ][10845] Returning information for job: 20190301023428162650
2019-03-01 02:34:43,874 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1386.22554207s left)
2019-03-01 02:34:58,200 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023458192388
2019-03-01 02:34:58,222 [salt.minion      :1432][INFO    ][10928] Starting a new job with PID 10928
2019-03-01 02:34:58,261 [salt.minion      :1711][INFO    ][10928] Returning information for job: 20190301023458192388
2019-03-01 02:35:16,426 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1353.67411709s left)
2019-03-01 02:35:28,339 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023528326539
2019-03-01 02:35:28,356 [salt.minion      :1432][INFO    ][10955] Starting a new job with PID 10955
2019-03-01 02:35:28,388 [salt.minion      :1711][INFO    ][10955] Returning information for job: 20190301023528326539
2019-03-01 02:35:49,165 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1320.93491507s left)
2019-03-01 02:35:58,508 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023558492548
2019-03-01 02:35:58,526 [salt.minion      :1432][INFO    ][11033] Starting a new job with PID 11033
2019-03-01 02:35:58,557 [salt.minion      :1711][INFO    ][11033] Returning information for job: 20190301023558492548
2019-03-01 02:36:21,513 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1288.58663297s left)
2019-03-01 02:36:28,661 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023628633567
2019-03-01 02:36:28,674 [salt.minion      :1432][INFO    ][11053] Starting a new job with PID 11053
2019-03-01 02:36:28,694 [salt.minion      :1711][INFO    ][11053] Returning information for job: 20190301023628633567
2019-03-01 02:36:53,846 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.25424004s left)
2019-03-01 02:36:58,787 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023658777875
2019-03-01 02:36:58,807 [salt.minion      :1432][INFO    ][11132] Starting a new job with PID 11132
2019-03-01 02:36:58,842 [salt.minion      :1711][INFO    ][11132] Returning information for job: 20190301023658777875
2019-03-01 02:37:26,621 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.47879195s left)
2019-03-01 02:37:28,922 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023728910377
2019-03-01 02:37:28,943 [salt.minion      :1432][INFO    ][11154] Starting a new job with PID 11154
2019-03-01 02:37:28,970 [salt.minion      :1711][INFO    ][11154] Returning information for job: 20190301023728910377
2019-03-01 02:37:59,017 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023759008847
2019-03-01 02:37:59,035 [salt.minion      :1432][INFO    ][11241] Starting a new job with PID 11241
2019-03-01 02:37:59,039 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1191.06047297s left)
2019-03-01 02:37:59,065 [salt.minion      :1711][INFO    ][11241] Returning information for job: 20190301023759008847
2019-03-01 02:38:29,126 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023829116909
2019-03-01 02:38:29,144 [salt.minion      :1432][INFO    ][11258] Starting a new job with PID 11258
2019-03-01 02:38:29,174 [salt.minion      :1711][INFO    ][11258] Returning information for job: 20190301023829116909
2019-03-01 02:38:31,462 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1158.63804102s left)
2019-03-01 02:38:59,309 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023859288323
2019-03-01 02:38:59,326 [salt.minion      :1432][INFO    ][11338] Starting a new job with PID 11338
2019-03-01 02:38:59,351 [salt.minion      :1711][INFO    ][11338] Returning information for job: 20190301023859288323
2019-03-01 02:39:03,932 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1126.16809011s left)
2019-03-01 02:39:29,492 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023929465636
2019-03-01 02:39:29,508 [salt.minion      :1432][INFO    ][11359] Starting a new job with PID 11359
2019-03-01 02:39:29,537 [salt.minion      :1711][INFO    ][11359] Returning information for job: 20190301023929465636
2019-03-01 02:39:36,553 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1093.54675913s left)
2019-03-01 02:39:59,673 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301023959656936
2019-03-01 02:39:59,688 [salt.minion      :1432][INFO    ][11440] Starting a new job with PID 11440
2019-03-01 02:39:59,717 [salt.minion      :1711][INFO    ][11440] Returning information for job: 20190301023959656936
2019-03-01 02:40:08,981 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1061.11886096s left)
2019-03-01 02:40:29,854 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024029836716
2019-03-01 02:40:29,870 [salt.minion      :1432][INFO    ][11461] Starting a new job with PID 11461
2019-03-01 02:40:29,901 [salt.minion      :1711][INFO    ][11461] Returning information for job: 20190301024029836716
2019-03-01 02:40:41,377 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1028.72328401s left)
2019-03-01 02:41:00,073 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024100055976
2019-03-01 02:41:00,091 [salt.minion      :1432][INFO    ][11564] Starting a new job with PID 11564
2019-03-01 02:41:00,116 [salt.minion      :1711][INFO    ][11564] Returning information for job: 20190301024100055976
2019-03-01 02:41:13,946 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (996.154199123s left)
2019-03-01 02:41:30,293 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024130276047
2019-03-01 02:41:30,312 [salt.minion      :1432][INFO    ][11588] Starting a new job with PID 11588
2019-03-01 02:41:30,344 [salt.minion      :1711][INFO    ][11588] Returning information for job: 20190301024130276047
2019-03-01 02:41:46,757 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (963.342597961s left)
2019-03-01 02:42:00,498 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024200485979
2019-03-01 02:42:00,514 [salt.minion      :1432][INFO    ][11671] Starting a new job with PID 11671
2019-03-01 02:42:00,541 [salt.minion      :1711][INFO    ][11671] Returning information for job: 20190301024200485979
2019-03-01 02:42:19,049 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (931.050486088s left)
2019-03-01 02:42:30,726 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024230708185
2019-03-01 02:42:30,753 [salt.minion      :1432][INFO    ][11692] Starting a new job with PID 11692
2019-03-01 02:42:30,784 [salt.minion      :1711][INFO    ][11692] Returning information for job: 20190301024230708185
2019-03-01 02:42:52,048 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (898.051576138s left)
2019-03-01 02:43:00,853 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024300842786
2019-03-01 02:43:00,870 [salt.minion      :1432][INFO    ][11772] Starting a new job with PID 11772
2019-03-01 02:43:00,899 [salt.minion      :1711][INFO    ][11772] Returning information for job: 20190301024300842786
2019-03-01 02:43:24,732 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (865.367527962s left)
2019-03-01 02:43:31,004 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024330990521
2019-03-01 02:43:31,031 [salt.minion      :1432][INFO    ][11795] Starting a new job with PID 11795
2019-03-01 02:43:31,060 [salt.minion      :1711][INFO    ][11795] Returning information for job: 20190301024330990521
2019-03-01 02:43:57,377 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (832.722378016s left)
2019-03-01 02:44:01,099 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024401049665
2019-03-01 02:44:01,117 [salt.minion      :1432][INFO    ][11881] Starting a new job with PID 11881
2019-03-01 02:44:01,148 [salt.minion      :1711][INFO    ][11881] Returning information for job: 20190301024401049665
2019-03-01 02:44:30,046 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (800.054068089s left)
2019-03-01 02:44:31,187 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024431173028
2019-03-01 02:44:31,206 [salt.minion      :1432][INFO    ][11902] Starting a new job with PID 11902
2019-03-01 02:44:31,236 [salt.minion      :1711][INFO    ][11902] Returning information for job: 20190301024431173028
2019-03-01 02:45:01,233 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024501223258
2019-03-01 02:45:01,246 [salt.minion      :1432][INFO    ][11981] Starting a new job with PID 11981
2019-03-01 02:45:01,279 [salt.minion      :1711][INFO    ][11981] Returning information for job: 20190301024501223258
2019-03-01 02:45:02,761 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (767.338499069s left)
2019-03-01 02:45:31,330 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024531321000
2019-03-01 02:45:31,350 [salt.minion      :1432][INFO    ][11999] Starting a new job with PID 11999
2019-03-01 02:45:31,384 [salt.minion      :1711][INFO    ][11999] Returning information for job: 20190301024531321000
2019-03-01 02:45:35,491 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (734.608790159s left)
2019-03-01 02:46:01,409 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024601390233
2019-03-01 02:46:01,427 [salt.minion      :1432][INFO    ][12082] Starting a new job with PID 12082
2019-03-01 02:46:01,457 [salt.minion      :1711][INFO    ][12082] Returning information for job: 20190301024601390233
2019-03-01 02:46:08,010 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (702.089668989s left)
2019-03-01 02:46:31,451 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024631437046
2019-03-01 02:46:31,471 [salt.minion      :1432][INFO    ][12105] Starting a new job with PID 12105
2019-03-01 02:46:31,499 [salt.minion      :1711][INFO    ][12105] Returning information for job: 20190301024631437046
2019-03-01 02:46:40,505 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (669.594763041s left)
2019-03-01 02:47:01,633 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024701624426
2019-03-01 02:47:01,651 [salt.minion      :1432][INFO    ][12191] Starting a new job with PID 12191
2019-03-01 02:47:01,681 [salt.minion      :1711][INFO    ][12191] Returning information for job: 20190301024701624426
2019-03-01 02:47:13,404 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (636.695583105s left)
2019-03-01 02:47:31,696 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024731687501
2019-03-01 02:47:31,715 [salt.minion      :1432][INFO    ][12221] Starting a new job with PID 12221
2019-03-01 02:47:31,747 [salt.minion      :1711][INFO    ][12221] Returning information for job: 20190301024731687501
2019-03-01 02:47:46,044 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (604.055897951s left)
2019-03-01 02:48:01,860 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024801853590
2019-03-01 02:48:01,877 [salt.minion      :1432][INFO    ][12294] Starting a new job with PID 12294
2019-03-01 02:48:01,907 [salt.minion      :1711][INFO    ][12294] Returning information for job: 20190301024801853590
2019-03-01 02:48:18,730 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (571.370095015s left)
2019-03-01 02:48:32,086 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024832078051
2019-03-01 02:48:32,108 [salt.minion      :1432][INFO    ][12324] Starting a new job with PID 12324
2019-03-01 02:48:32,135 [salt.minion      :1711][INFO    ][12324] Returning information for job: 20190301024832078051
2019-03-01 02:48:51,717 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (538.382357121s left)
2019-03-01 02:49:02,277 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024902262617
2019-03-01 02:49:02,299 [salt.minion      :1432][INFO    ][12397] Starting a new job with PID 12397
2019-03-01 02:49:02,329 [salt.minion      :1711][INFO    ][12397] Returning information for job: 20190301024902262617
2019-03-01 02:49:24,285 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (505.814746141s left)
2019-03-01 02:49:32,348 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301024932333233
2019-03-01 02:49:32,366 [salt.minion      :1432][INFO    ][12427] Starting a new job with PID 12427
2019-03-01 02:49:32,396 [salt.minion      :1711][INFO    ][12427] Returning information for job: 20190301024932333233
2019-03-01 02:49:57,033 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (473.066508055s left)
2019-03-01 02:50:02,371 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025002361549
2019-03-01 02:50:02,385 [salt.minion      :1432][INFO    ][12501] Starting a new job with PID 12501
2019-03-01 02:50:02,414 [salt.minion      :1711][INFO    ][12501] Returning information for job: 20190301025002361549
2019-03-01 02:50:29,788 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (440.311383963s left)
2019-03-01 02:50:32,458 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025032451161
2019-03-01 02:50:32,478 [salt.minion      :1432][INFO    ][12663] Starting a new job with PID 12663
2019-03-01 02:50:32,509 [salt.minion      :1711][INFO    ][12663] Returning information for job: 20190301025032451161
2019-03-01 02:51:02,317 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (407.783056974s left)
2019-03-01 02:51:02,585 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025102570798
2019-03-01 02:51:02,606 [salt.minion      :1432][INFO    ][12753] Starting a new job with PID 12753
2019-03-01 02:51:02,640 [salt.minion      :1711][INFO    ][12753] Returning information for job: 20190301025102570798
2019-03-01 02:51:32,685 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025132674578
2019-03-01 02:51:32,707 [salt.minion      :1432][INFO    ][12779] Starting a new job with PID 12779
2019-03-01 02:51:32,736 [salt.minion      :1711][INFO    ][12779] Returning information for job: 20190301025132674578
2019-03-01 02:51:34,988 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (375.111410141s left)
2019-03-01 02:52:02,799 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025202791814
2019-03-01 02:52:02,813 [salt.minion      :1432][INFO    ][12853] Starting a new job with PID 12853
2019-03-01 02:52:02,844 [salt.minion      :1711][INFO    ][12853] Returning information for job: 20190301025202791814
2019-03-01 02:52:07,787 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (342.312469959s left)
2019-03-01 02:52:32,998 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025232985470
2019-03-01 02:52:33,022 [salt.minion      :1432][INFO    ][12884] Starting a new job with PID 12884
2019-03-01 02:52:33,054 [salt.minion      :1711][INFO    ][12884] Returning information for job: 20190301025232985470
2019-03-01 02:52:40,313 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (309.786448956s left)
2019-03-01 02:53:03,128 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025303116502
2019-03-01 02:53:03,151 [salt.minion      :1432][INFO    ][12958] Starting a new job with PID 12958
2019-03-01 02:53:03,187 [salt.minion      :1711][INFO    ][12958] Returning information for job: 20190301025303116502
2019-03-01 02:53:12,968 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (277.131373167s left)
2019-03-01 02:53:33,298 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025333288881
2019-03-01 02:53:33,320 [salt.minion      :1432][INFO    ][12987] Starting a new job with PID 12987
2019-03-01 02:53:33,350 [salt.minion      :1711][INFO    ][12987] Returning information for job: 20190301025333288881
2019-03-01 02:53:45,405 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (244.695069075s left)
2019-03-01 02:54:03,518 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025403511297
2019-03-01 02:54:03,534 [salt.minion      :1432][INFO    ][13059] Starting a new job with PID 13059
2019-03-01 02:54:03,572 [salt.minion      :1711][INFO    ][13059] Returning information for job: 20190301025403511297
2019-03-01 02:54:18,089 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (212.011041164s left)
2019-03-01 02:54:33,597 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025433583173
2019-03-01 02:54:33,617 [salt.minion      :1432][INFO    ][13088] Starting a new job with PID 13088
2019-03-01 02:54:33,649 [salt.minion      :1711][INFO    ][13088] Returning information for job: 20190301025433583173
2019-03-01 02:54:50,816 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (179.284192085s left)
2019-03-01 02:55:03,638 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025503623789
2019-03-01 02:55:03,665 [salt.minion      :1432][INFO    ][13164] Starting a new job with PID 13164
2019-03-01 02:55:03,697 [salt.minion      :1711][INFO    ][13164] Returning information for job: 20190301025503623789
2019-03-01 02:55:23,716 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (146.383457184s left)
2019-03-01 02:55:33,657 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025533642803
2019-03-01 02:55:33,679 [salt.minion      :1432][INFO    ][13193] Starting a new job with PID 13193
2019-03-01 02:55:33,712 [salt.minion      :1711][INFO    ][13193] Returning information for job: 20190301025533642803
2019-03-01 02:55:56,412 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (113.688258171s left)
2019-03-01 02:56:03,742 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025603730991
2019-03-01 02:56:03,763 [salt.minion      :1432][INFO    ][13268] Starting a new job with PID 13268
2019-03-01 02:56:03,794 [salt.minion      :1711][INFO    ][13268] Returning information for job: 20190301025603730991
2019-03-01 02:56:28,989 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (81.1105451584s left)
2019-03-01 02:56:33,833 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025633825440
2019-03-01 02:56:33,849 [salt.minion      :1432][INFO    ][13297] Starting a new job with PID 13297
2019-03-01 02:56:33,880 [salt.minion      :1711][INFO    ][13297] Returning information for job: 20190301025633825440
2019-03-01 02:57:01,593 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (48.5064921379s left)
2019-03-01 02:57:04,060 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025704048217
2019-03-01 02:57:04,079 [salt.minion      :1432][INFO    ][13373] Starting a new job with PID 13373
2019-03-01 02:57:04,113 [salt.minion      :1711][INFO    ][13373] Returning information for job: 20190301025704048217
2019-03-01 02:57:34,093 [salt.loaded.ext.module.maas:1023][INFO    ][7074] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (16.0067331791s left)
2019-03-01 02:57:34,198 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025734191101
2019-03-01 02:57:34,220 [salt.minion      :1432][INFO    ][13402] Starting a new job with PID 13402
2019-03-01 02:57:34,254 [salt.minion      :1711][INFO    ][13402] Returning information for job: 20190301025734191101
2019-03-01 02:58:04,326 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025804313497
2019-03-01 02:58:04,344 [salt.minion      :1432][INFO    ][13471] Starting a new job with PID 13471
2019-03-01 02:58:04,380 [salt.minion      :1711][INFO    ][13471] Returning information for job: 20190301025804313497
2019-03-01 02:58:06,776 [salt.state       :302 ][ERROR   ][7074] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-03-01 02:58:06,776 [salt.state       :1951][INFO    ][7074] Completed state [maas.wait_for_machine_status] at time 02:58:06.776561 duration_in_ms=2266689.922
2019-03-01 02:58:06,785 [salt.minion      :1711][INFO    ][7074] Returning information for job: 20190301022009235758
2019-03-01 02:58:18,917 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command pillar.get with jid 20190301025818906235
2019-03-01 02:58:18,936 [salt.minion      :1432][INFO    ][13486] Starting a new job with PID 13486
2019-03-01 02:58:18,948 [salt.minion      :1711][INFO    ][13486] Returning information for job: 20190301025818906235
2019-03-01 02:58:19,642 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command service.status with jid 20190301025819629153
2019-03-01 02:58:19,665 [salt.minion      :1432][INFO    ][13492] Starting a new job with PID 13492
2019-03-01 02:58:21,133 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13492] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-03-01 02:58:21,182 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13492] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-03-01 02:58:21,202 [salt.minion      :1711][INFO    ][13492] Returning information for job: 20190301025819629153
2019-03-01 02:58:21,886 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301025821875220
2019-03-01 02:58:21,907 [salt.minion      :1432][INFO    ][13503] Starting a new job with PID 13503
2019-03-01 02:58:30,836 [salt.state       :915 ][INFO    ][13503] Loading fresh modules for state activity
2019-03-01 02:58:31,478 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command 'salt-minion --version' in directory '/root'
2019-03-01 02:58:32,039 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command 'salt-minion --version' in directory '/root'
2019-03-01 02:58:33,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command 'salt-minion --version' in directory '/root'
2019-03-01 02:58:33,416 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command 'salt-minion --version' in directory '/root'
2019-03-01 02:58:35,825 [salt.state       :1780][INFO    ][13503] Running state [salt-minion] at time 02:58:35.825023
2019-03-01 02:58:35,825 [salt.state       :1813][INFO    ][13503] Executing state pkg.installed for [salt-minion]
2019-03-01 02:58:35,826 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-01 02:58:35,960 [salt.state       :300 ][INFO    ][13503] All specified packages are already installed
2019-03-01 02:58:35,960 [salt.state       :1951][INFO    ][13503] Completed state [salt-minion] at time 02:58:35.960872 duration_in_ms=135.85
2019-03-01 02:58:35,961 [salt.state       :1780][INFO    ][13503] Running state [salt_minion_dependency_packages] at time 02:58:35.961263
2019-03-01 02:58:35,961 [salt.state       :1813][INFO    ][13503] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-03-01 02:58:35,971 [salt.state       :300 ][INFO    ][13503] All specified packages are already installed
2019-03-01 02:58:35,972 [salt.state       :1951][INFO    ][13503] Completed state [salt_minion_dependency_packages] at time 02:58:35.971939 duration_in_ms=10.676
2019-03-01 02:58:35,977 [salt.state       :1780][INFO    ][13503] Running state [/etc/salt/minion.d/minion.conf] at time 02:58:35.976999
2019-03-01 02:58:35,977 [salt.state       :1813][INFO    ][13503] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-03-01 02:58:36,252 [salt.state       :300 ][INFO    ][13503] File /etc/salt/minion.d/minion.conf is in the correct state
2019-03-01 02:58:36,252 [salt.state       :1951][INFO    ][13503] Completed state [/etc/salt/minion.d/minion.conf] at time 02:58:36.252760 duration_in_ms=275.762
2019-03-01 02:58:36,253 [salt.state       :1780][INFO    ][13503] Running state [python-netaddr] at time 02:58:36.253068
2019-03-01 02:58:36,253 [salt.state       :1813][INFO    ][13503] Executing state pkg.installed for [python-netaddr]
2019-03-01 02:58:36,269 [salt.state       :300 ][INFO    ][13503] All specified packages are already installed
2019-03-01 02:58:36,270 [salt.state       :1951][INFO    ][13503] Completed state [python-netaddr] at time 02:58:36.270068 duration_in_ms=17.0
2019-03-01 02:58:36,276 [salt.state       :1780][INFO    ][13503] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:58:36.276289
2019-03-01 02:58:36,276 [salt.state       :1813][INFO    ][13503] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-03-01 02:58:36,294 [salt.state       :300 ][INFO    ][13503] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-03-01 02:58:36,294 [salt.state       :1951][INFO    ][13503] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:58:36.294528 duration_in_ms=18.238
2019-03-01 02:58:36,296 [salt.state       :1780][INFO    ][13503] Running state [salt-minion] at time 02:58:36.296058
2019-03-01 02:58:36,296 [salt.state       :1813][INFO    ][13503] Executing state service.running for [salt-minion]
2019-03-01 02:58:36,297 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-01 02:58:36,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-03-01 02:58:36,357 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-03-01 02:58:36,379 [salt.state       :300 ][INFO    ][13503] The service salt-minion is already running
2019-03-01 02:58:36,380 [salt.state       :1951][INFO    ][13503] Completed state [salt-minion] at time 02:58:36.380438 duration_in_ms=84.379
2019-03-01 02:58:36,383 [salt.state       :1780][INFO    ][13503] Running state [/etc/salt/grains.d] at time 02:58:36.383300
2019-03-01 02:58:36,383 [salt.state       :1813][INFO    ][13503] Executing state file.directory for [/etc/salt/grains.d]
2019-03-01 02:58:36,385 [salt.state       :300 ][INFO    ][13503] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-03-01 02:58:36,386 [salt.state       :1951][INFO    ][13503] Completed state [/etc/salt/grains.d] at time 02:58:36.386187 duration_in_ms=2.887
2019-03-01 02:58:36,389 [salt.state       :1780][INFO    ][13503] Running state [/etc/salt/grains] at time 02:58:36.389194
2019-03-01 02:58:36,389 [salt.state       :1813][INFO    ][13503] Executing state file.managed for [/etc/salt/grains]
2019-03-01 02:58:36,390 [salt.state       :300 ][INFO    ][13503] File /etc/salt/grains exists with proper permissions. No changes made.
2019-03-01 02:58:36,390 [salt.state       :1951][INFO    ][13503] Completed state [/etc/salt/grains] at time 02:58:36.390426 duration_in_ms=1.232
2019-03-01 02:58:36,391 [salt.state       :1780][INFO    ][13503] Running state [/etc/salt/grains.d/placeholder] at time 02:58:36.390998
2019-03-01 02:58:36,391 [salt.state       :1813][INFO    ][13503] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-03-01 02:58:36,392 [salt.state       :300 ][INFO    ][13503] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-03-01 02:58:36,392 [salt.state       :1951][INFO    ][13503] Completed state [/etc/salt/grains.d/placeholder] at time 02:58:36.392217 duration_in_ms=1.218
2019-03-01 02:58:36,392 [salt.state       :1780][INFO    ][13503] Running state [/etc/salt/grains.d/sphinx] at time 02:58:36.392775
2019-03-01 02:58:36,393 [salt.state       :1813][INFO    ][13503] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-03-01 02:58:36,394 [salt.state       :300 ][INFO    ][13503] File /etc/salt/grains.d/sphinx is in the correct state
2019-03-01 02:58:36,394 [salt.state       :1951][INFO    ][13503] Completed state [/etc/salt/grains.d/sphinx] at time 02:58:36.394897 duration_in_ms=2.122
2019-03-01 02:58:36,397 [salt.state       :1780][INFO    ][13503] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:58:36.397225
2019-03-01 02:58:36,397 [salt.state       :1813][INFO    ][13503] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-03-01 02:58:36,397 [salt.state       :300 ][INFO    ][13503] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-03-01 02:58:36,398 [salt.state       :1951][INFO    ][13503] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:58:36.398167 duration_in_ms=0.942
2019-03-01 02:58:36,398 [salt.state       :1780][INFO    ][13503] Running state [/etc/salt/grains.d/dns_records] at time 02:58:36.398746
2019-03-01 02:58:36,400 [salt.state       :1813][INFO    ][13503] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-03-01 02:58:36,401 [salt.state       :300 ][INFO    ][13503] File /etc/salt/grains.d/dns_records is in the correct state
2019-03-01 02:58:36,401 [salt.state       :1951][INFO    ][13503] Completed state [/etc/salt/grains.d/dns_records] at time 02:58:36.401856 duration_in_ms=3.109
2019-03-01 02:58:36,403 [salt.state       :1780][INFO    ][13503] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:58:36.402817
2019-03-01 02:58:36,403 [salt.state       :1813][INFO    ][13503] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-03-01 02:58:36,403 [salt.state       :300 ][INFO    ][13503] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-03-01 02:58:36,403 [salt.state       :1951][INFO    ][13503] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:58:36.403833 duration_in_ms=1.017
2019-03-01 02:58:36,404 [salt.state       :1780][INFO    ][13503] Running state [/etc/salt/grains.d/salt] at time 02:58:36.404358
2019-03-01 02:58:36,404 [salt.state       :1813][INFO    ][13503] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-03-01 02:58:36,405 [salt.state       :300 ][INFO    ][13503] File /etc/salt/grains.d/salt is in the correct state
2019-03-01 02:58:36,406 [salt.state       :1951][INFO    ][13503] Completed state [/etc/salt/grains.d/salt] at time 02:58:36.406051 duration_in_ms=1.693
2019-03-01 02:58:36,407 [salt.state       :1780][INFO    ][13503] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:58:36.407273
2019-03-01 02:58:36,407 [salt.state       :1813][INFO    ][13503] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-03-01 02:58:36,407 [salt.state       :300 ][INFO    ][13503] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-03-01 02:58:36,408 [salt.state       :1951][INFO    ][13503] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:58:36.408112 duration_in_ms=0.839
2019-03-01 02:58:36,410 [salt.state       :1780][INFO    ][13503] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:58:36.410072
2019-03-01 02:58:36,410 [salt.state       :1813][INFO    ][13503] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-03-01 02:58:36,410 [salt.state       :300 ][INFO    ][13503] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-03-01 02:58:36,412 [salt.state       :1951][INFO    ][13503] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:58:36.412336 duration_in_ms=2.264
2019-03-01 02:58:36,413 [salt.state       :1780][INFO    ][13503] Running state [mine.update] at time 02:58:36.413067
2019-03-01 02:58:36,413 [salt.state       :1813][INFO    ][13503] Executing state module.wait for [mine.update]
2019-03-01 02:58:36,413 [salt.state       :300 ][INFO    ][13503] No changes made for mine.update
2019-03-01 02:58:36,413 [salt.state       :1951][INFO    ][13503] Completed state [mine.update] at time 02:58:36.413873 duration_in_ms=0.806
2019-03-01 02:58:36,414 [salt.state       :1780][INFO    ][13503] Running state [ca-certificates] at time 02:58:36.414155
2019-03-01 02:58:36,414 [salt.state       :1813][INFO    ][13503] Executing state pkg.installed for [ca-certificates]
2019-03-01 02:58:36,427 [salt.state       :300 ][INFO    ][13503] All specified packages are already installed
2019-03-01 02:58:36,427 [salt.state       :1951][INFO    ][13503] Completed state [ca-certificates] at time 02:58:36.427562 duration_in_ms=13.407
2019-03-01 02:58:36,428 [salt.state       :1780][INFO    ][13503] Running state [update-ca-certificates] at time 02:58:36.428284
2019-03-01 02:58:36,428 [salt.state       :1813][INFO    ][13503] Executing state cmd.wait for [update-ca-certificates]
2019-03-01 02:58:36,428 [salt.state       :300 ][INFO    ][13503] No changes made for update-ca-certificates
2019-03-01 02:58:36,429 [salt.state       :1951][INFO    ][13503] Completed state [update-ca-certificates] at time 02:58:36.429096 duration_in_ms=0.812
2019-03-01 02:58:36,429 [salt.state       :1780][INFO    ][13503] Running state [iptables] at time 02:58:36.429375
2019-03-01 02:58:36,429 [salt.state       :1813][INFO    ][13503] Executing state pkg.installed for [iptables]
2019-03-01 02:58:36,438 [salt.state       :300 ][INFO    ][13503] All specified packages are already installed
2019-03-01 02:58:36,439 [salt.state       :1951][INFO    ][13503] Completed state [iptables] at time 02:58:36.439163 duration_in_ms=9.788
2019-03-01 02:58:36,439 [salt.state       :1780][INFO    ][13503] Running state [iptables-persistent] at time 02:58:36.439452
2019-03-01 02:58:36,439 [salt.state       :1813][INFO    ][13503] Executing state pkg.installed for [iptables-persistent]
2019-03-01 02:58:36,449 [salt.state       :300 ][INFO    ][13503] All specified packages are already installed
2019-03-01 02:58:36,449 [salt.state       :1951][INFO    ][13503] Completed state [iptables-persistent] at time 02:58:36.449496 duration_in_ms=10.044
2019-03-01 02:58:36,451 [salt.state       :1780][INFO    ][13503] Running state [iptables_modules_v4_load] at time 02:58:36.451844
2019-03-01 02:58:36,452 [salt.state       :1813][INFO    ][13503] Executing state kmod.present for [iptables_modules_v4_load]
2019-03-01 02:58:36,453 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command 'lsmod' in directory '/root'
2019-03-01 02:58:36,477 [salt.state       :300 ][INFO    ][13503] Kernel modules iptable_filter, ip_tables are already present
2019-03-01 02:58:36,478 [salt.state       :1951][INFO    ][13503] Completed state [iptables_modules_v4_load] at time 02:58:36.478119 duration_in_ms=26.276
2019-03-01 02:58:36,479 [salt.state       :1780][INFO    ][13503] Running state [/etc/iptables/rules.v4] at time 02:58:36.479246
2019-03-01 02:58:36,479 [salt.state       :1813][INFO    ][13503] Executing state file.managed for [/etc/iptables/rules.v4]
2019-03-01 02:58:36,591 [salt.state       :300 ][INFO    ][13503] File /etc/iptables/rules.v4 is in the correct state
2019-03-01 02:58:36,592 [salt.state       :1951][INFO    ][13503] Completed state [/etc/iptables/rules.v4] at time 02:58:36.592207 duration_in_ms=112.962
2019-03-01 02:58:36,593 [salt.state       :1780][INFO    ][13503] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:58:36.593524
2019-03-01 02:58:36,593 [salt.state       :1813][INFO    ][13503] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-03-01 02:58:36,594 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-03-01 02:58:36,619 [salt.state       :300 ][INFO    ][13503] onlyif execution failed
2019-03-01 02:58:36,620 [salt.state       :1951][INFO    ][13503] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:58:36.620451 duration_in_ms=26.927
2019-03-01 02:58:36,622 [salt.state       :1780][INFO    ][13503] Running state [netfilter-persistent] at time 02:58:36.622508
2019-03-01 02:58:36,623 [salt.state       :1813][INFO    ][13503] Executing state service.running for [netfilter-persistent]
2019-03-01 02:58:36,624 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-03-01 02:58:36,649 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-03-01 02:58:36,670 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-03-01 02:58:36,691 [salt.state       :300 ][INFO    ][13503] The service netfilter-persistent is already running
2019-03-01 02:58:36,691 [salt.state       :1951][INFO    ][13503] Completed state [netfilter-persistent] at time 02:58:36.691637 duration_in_ms=69.129
2019-03-01 02:58:36,693 [salt.state       :1780][INFO    ][13503] Running state [iptables_extra.remove_stale_tables] at time 02:58:36.693259
2019-03-01 02:58:36,693 [salt.state       :1813][INFO    ][13503] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-03-01 02:58:36,694 [salt.state       :300 ][INFO    ][13503] No changes made for iptables_extra.remove_stale_tables
2019-03-01 02:58:36,694 [salt.state       :1951][INFO    ][13503] Completed state [iptables_extra.remove_stale_tables] at time 02:58:36.694894 duration_in_ms=1.636
2019-03-01 02:58:36,695 [salt.state       :1780][INFO    ][13503] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:58:36.695252
2019-03-01 02:58:36,695 [salt.state       :1813][INFO    ][13503] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-03-01 02:58:36,696 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13503] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-03-01 02:58:36,716 [salt.state       :300 ][INFO    ][13503] onlyif execution failed
2019-03-01 02:58:36,716 [salt.state       :1951][INFO    ][13503] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:58:36.716515 duration_in_ms=21.263
2019-03-01 02:58:36,718 [salt.state       :1780][INFO    ][13503] Running state [/etc/iptables/rules.v6] at time 02:58:36.718066
2019-03-01 02:58:36,718 [salt.state       :1813][INFO    ][13503] Executing state file.absent for [/etc/iptables/rules.v6]
2019-03-01 02:58:36,719 [salt.state       :300 ][INFO    ][13503] File /etc/iptables/rules.v6 is not present
2019-03-01 02:58:36,719 [salt.state       :1951][INFO    ][13503] Completed state [/etc/iptables/rules.v6] at time 02:58:36.719755 duration_in_ms=1.689
2019-03-01 02:58:36,721 [salt.state       :1780][INFO    ][13503] Running state [iptables_extra.flush_all] at time 02:58:36.720919
2019-03-01 02:58:36,721 [salt.state       :1813][INFO    ][13503] Executing state module.wait for [iptables_extra.flush_all]
2019-03-01 02:58:36,721 [salt.state       :300 ][INFO    ][13503] No changes made for iptables_extra.flush_all
2019-03-01 02:58:36,722 [salt.state       :1951][INFO    ][13503] Completed state [iptables_extra.flush_all] at time 02:58:36.722258 duration_in_ms=1.339
2019-03-01 02:58:36,728 [salt.minion      :1711][INFO    ][13503] Returning information for job: 20190301025821875220
2019-03-01 02:58:37,439 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301025837428490
2019-03-01 02:58:37,460 [salt.minion      :1432][INFO    ][13592] Starting a new job with PID 13592
2019-03-01 02:58:38,552 [salt.state       :915 ][INFO    ][13592] Loading fresh modules for state activity
2019-03-01 02:58:39,801 [salt.state       :1780][INFO    ][13592] Running state [maas-rack-controller] at time 02:58:39.801230
2019-03-01 02:58:39,801 [salt.state       :1813][INFO    ][13592] Executing state pkg.installed for [maas-rack-controller]
2019-03-01 02:58:39,802 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13592] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-01 02:58:39,916 [salt.state       :300 ][INFO    ][13592] All specified packages are already installed
2019-03-01 02:58:39,917 [salt.state       :1951][INFO    ][13592] Completed state [maas-rack-controller] at time 02:58:39.916996 duration_in_ms=115.767
2019-03-01 02:58:39,917 [salt.state       :1780][INFO    ][13592] Running state [ipmitool] at time 02:58:39.917372
2019-03-01 02:58:39,917 [salt.state       :1813][INFO    ][13592] Executing state pkg.installed for [ipmitool]
2019-03-01 02:58:39,926 [salt.state       :300 ][INFO    ][13592] All specified packages are already installed
2019-03-01 02:58:39,926 [salt.state       :1951][INFO    ][13592] Completed state [ipmitool] at time 02:58:39.926682 duration_in_ms=9.31
2019-03-01 02:58:39,930 [salt.state       :1780][INFO    ][13592] Running state [/etc/maas/rackd.conf] at time 02:58:39.930567
2019-03-01 02:58:39,930 [salt.state       :1813][INFO    ][13592] Executing state file.line for [/etc/maas/rackd.conf]
2019-03-01 02:58:39,932 [salt.state       :300 ][INFO    ][13592] No changes needed to be made
2019-03-01 02:58:39,932 [salt.state       :1951][INFO    ][13592] Completed state [/etc/maas/rackd.conf] at time 02:58:39.932262 duration_in_ms=1.694
2019-03-01 02:58:39,932 [salt.state       :1780][INFO    ][13592] Running state [/etc/maas/rackd.conf] at time 02:58:39.932550
2019-03-01 02:58:39,932 [salt.state       :1813][INFO    ][13592] Executing state file.managed for [/etc/maas/rackd.conf]
2019-03-01 02:58:39,933 [salt.loaded.int.states.file:2298][WARNING ][13592] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-01 02:58:39,933 [salt.state       :300 ][INFO    ][13592] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-03-01 02:58:39,934 [salt.state       :1951][INFO    ][13592] Completed state [/etc/maas/rackd.conf] at time 02:58:39.934026 duration_in_ms=1.477
2019-03-01 02:58:39,935 [salt.state       :1780][INFO    ][13592] Running state [maas-rackd] at time 02:58:39.935156
2019-03-01 02:58:39,935 [salt.state       :1813][INFO    ][13592] Executing state service.running for [maas-rackd]
2019-03-01 02:58:39,936 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13592] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-03-01 02:58:39,971 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13592] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-03-01 02:58:39,986 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13592] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-03-01 02:58:40,006 [salt.state       :300 ][INFO    ][13592] The service maas-rackd is already running
2019-03-01 02:58:40,007 [salt.state       :1951][INFO    ][13592] Completed state [maas-rackd] at time 02:58:40.007192 duration_in_ms=72.035
2019-03-01 02:58:40,009 [salt.minion      :1711][INFO    ][13592] Returning information for job: 20190301025837428490
2019-03-01 02:58:40,701 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301025840687920
2019-03-01 02:58:40,723 [salt.minion      :1432][INFO    ][13615] Starting a new job with PID 13615
2019-03-01 02:58:41,819 [salt.state       :915 ][INFO    ][13615] Loading fresh modules for state activity
2019-03-01 02:58:43,214 [salt.state       :1780][INFO    ][13615] Running state [maas-region-controller] at time 02:58:43.214736
2019-03-01 02:58:43,216 [salt.state       :1813][INFO    ][13615] Executing state pkg.installed for [maas-region-controller]
2019-03-01 02:58:43,216 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-01 02:58:43,308 [salt.state       :300 ][INFO    ][13615] All specified packages are already installed
2019-03-01 02:58:43,308 [salt.state       :1951][INFO    ][13615] Completed state [maas-region-controller] at time 02:58:43.308851 duration_in_ms=94.116
2019-03-01 02:58:43,309 [salt.state       :1780][INFO    ][13615] Running state [python-oauth] at time 02:58:43.309237
2019-03-01 02:58:43,309 [salt.state       :1813][INFO    ][13615] Executing state pkg.installed for [python-oauth]
2019-03-01 02:58:43,319 [salt.state       :300 ][INFO    ][13615] All specified packages are already installed
2019-03-01 02:58:43,319 [salt.state       :1951][INFO    ][13615] Completed state [python-oauth] at time 02:58:43.319255 duration_in_ms=10.017
2019-03-01 02:58:43,322 [salt.state       :1780][INFO    ][13615] Running state [/etc/maas/regiond.conf] at time 02:58:43.322403
2019-03-01 02:58:43,322 [salt.state       :1813][INFO    ][13615] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-01 02:58:43,328 [salt.state       :300 ][INFO    ][13615] No changes needed to be made
2019-03-01 02:58:43,328 [salt.state       :1951][INFO    ][13615] Completed state [/etc/maas/regiond.conf] at time 02:58:43.328655 duration_in_ms=6.252
2019-03-01 02:58:43,329 [salt.state       :1780][INFO    ][13615] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:58:43.329202
2019-03-01 02:58:43,329 [salt.state       :1813][INFO    ][13615] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-01 02:58:43,381 [salt.state       :300 ][INFO    ][13615] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-01 02:58:43,381 [salt.state       :1951][INFO    ][13615] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:58:43.381680 duration_in_ms=52.478
2019-03-01 02:58:43,382 [salt.state       :1780][INFO    ][13615] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:58:43.382252
2019-03-01 02:58:43,382 [salt.state       :1813][INFO    ][13615] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-01 02:58:43,389 [salt.state       :300 ][INFO    ][13615] No changes needed to be made
2019-03-01 02:58:43,389 [salt.state       :1951][INFO    ][13615] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:58:43.389278 duration_in_ms=7.026
2019-03-01 02:58:43,389 [salt.state       :1780][INFO    ][13615] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:58:43.389811
2019-03-01 02:58:43,390 [salt.state       :1813][INFO    ][13615] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-01 02:58:43,394 [salt.state       :300 ][INFO    ][13615] No changes needed to be made
2019-03-01 02:58:43,394 [salt.state       :1951][INFO    ][13615] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:58:43.394502 duration_in_ms=4.691
2019-03-01 02:58:43,395 [salt.state       :1780][INFO    ][13615] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:58:43.395619
2019-03-01 02:58:43,395 [salt.state       :1813][INFO    ][13615] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-01 02:58:43,421 [salt.state       :300 ][INFO    ][13615] No changes needed to be made
2019-03-01 02:58:43,421 [salt.state       :1951][INFO    ][13615] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:58:43.421426 duration_in_ms=25.806
2019-03-01 02:58:43,422 [salt.state       :1780][INFO    ][13615] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:58:43.421969
2019-03-01 02:58:43,422 [salt.state       :1813][INFO    ][13615] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-01 02:58:43,439 [salt.state       :300 ][INFO    ][13615] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-01 02:58:43,439 [salt.state       :1951][INFO    ][13615] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:58:43.439648 duration_in_ms=17.678
2019-03-01 02:58:43,441 [salt.state       :1780][INFO    ][13615] Running state [a2enmod headers] at time 02:58:43.441290
2019-03-01 02:58:43,441 [salt.state       :1813][INFO    ][13615] Executing state cmd.run for [a2enmod headers]
2019-03-01 02:58:43,442 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command 'a2enmod headers' in directory '/root'
2019-03-01 02:58:43,506 [salt.state       :300 ][INFO    ][13615] {'pid': 13635, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-01 02:58:43,507 [salt.state       :1951][INFO    ][13615] Completed state [a2enmod headers] at time 02:58:43.507161 duration_in_ms=65.871
2019-03-01 02:58:43,507 [salt.state       :1780][INFO    ][13615] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:58:43.507561
2019-03-01 02:58:43,507 [salt.state       :1813][INFO    ][13615] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-01 02:58:43,532 [salt.state       :300 ][INFO    ][13615] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-01 02:58:43,532 [salt.state       :1951][INFO    ][13615] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:58:43.532277 duration_in_ms=24.716
2019-03-01 02:58:43,532 [salt.state       :1780][INFO    ][13615] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:58:43.532932
2019-03-01 02:58:43,533 [salt.state       :1813][INFO    ][13615] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-01 02:58:43,583 [salt.state       :300 ][INFO    ][13615] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-01 02:58:43,583 [salt.state       :1951][INFO    ][13615] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:58:43.583887 duration_in_ms=50.955
2019-03-01 02:58:43,584 [salt.state       :1780][INFO    ][13615] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:58:43.584439
2019-03-01 02:58:43,584 [salt.state       :1813][INFO    ][13615] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-01 02:58:43,637 [salt.state       :300 ][INFO    ][13615] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-01 02:58:43,637 [salt.state       :1951][INFO    ][13615] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:58:43.637634 duration_in_ms=53.194
2019-03-01 02:58:43,638 [salt.state       :1780][INFO    ][13615] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:58:43.638170
2019-03-01 02:58:43,638 [salt.state       :1813][INFO    ][13615] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-01 02:58:43,706 [salt.state       :300 ][INFO    ][13615] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-01 02:58:43,706 [salt.state       :1951][INFO    ][13615] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:58:43.706674 duration_in_ms=68.504
2019-03-01 02:58:43,708 [salt.state       :1780][INFO    ][13615] Running state [/root/.pgpass] at time 02:58:43.708087
2019-03-01 02:58:43,708 [salt.state       :1813][INFO    ][13615] Executing state file.managed for [/root/.pgpass]
2019-03-01 02:58:43,748 [salt.state       :300 ][INFO    ][13615] File /root/.pgpass is in the correct state
2019-03-01 02:58:43,748 [salt.state       :1951][INFO    ][13615] Completed state [/root/.pgpass] at time 02:58:43.748296 duration_in_ms=40.209
2019-03-01 02:58:43,753 [salt.state       :1780][INFO    ][13615] Running state [maas-region syncdb --noinput] at time 02:58:43.753245
2019-03-01 02:58:43,753 [salt.state       :1813][INFO    ][13615] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-01 02:58:43,754 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-01 02:58:46,721 [salt.state       :300 ][INFO    ][13615] {'pid': 13648, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, sessions, metadataserver, contenttypes, maasserver, auth, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-01 02:58:46,722 [salt.state       :1951][INFO    ][13615] Completed state [maas-region syncdb --noinput] at time 02:58:46.722324 duration_in_ms=2969.078
2019-03-01 02:58:46,722 [salt.state       :2022][WARNING ][13615] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-01 02:58:46,724 [salt.state       :1780][INFO    ][13615] Running state [maas-regiond] at time 02:58:46.724892
2019-03-01 02:58:46,725 [salt.state       :1813][INFO    ][13615] Executing state service.running for [maas-regiond]
2019-03-01 02:58:46,726 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-01 02:58:46,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-01 02:58:46,764 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-01 02:58:46,776 [salt.state       :300 ][INFO    ][13615] The service maas-regiond is already running
2019-03-01 02:58:46,776 [salt.state       :1951][INFO    ][13615] Completed state [maas-regiond] at time 02:58:46.776724 duration_in_ms=51.832
2019-03-01 02:58:46,778 [salt.state       :1780][INFO    ][13615] Running state [bind9] at time 02:58:46.778487
2019-03-01 02:58:46,780 [salt.state       :1813][INFO    ][13615] Executing state service.running for [bind9]
2019-03-01 02:58:46,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-01 02:58:46,794 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-01 02:58:46,805 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-01 02:58:46,817 [salt.state       :300 ][INFO    ][13615] The service bind9 is already running
2019-03-01 02:58:46,818 [salt.state       :1951][INFO    ][13615] Completed state [bind9] at time 02:58:46.818077 duration_in_ms=39.589
2019-03-01 02:58:46,820 [salt.state       :1780][INFO    ][13615] Running state [apache2] at time 02:58:46.819978
2019-03-01 02:58:46,820 [salt.state       :1813][INFO    ][13615] Executing state service.running for [apache2]
2019-03-01 02:58:46,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-01 02:58:46,835 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-01 02:58:46,848 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-01 02:58:46,868 [salt.state       :300 ][INFO    ][13615] The service apache2 is already running
2019-03-01 02:58:46,868 [salt.state       :1951][INFO    ][13615] Completed state [apache2] at time 02:58:46.868452 duration_in_ms=48.472
2019-03-01 02:58:46,869 [salt.state       :1780][INFO    ][13615] Running state [maasng.wait_for_http_code] at time 02:58:46.869743
2019-03-01 02:58:46,870 [salt.state       :1813][INFO    ][13615] Executing state module.run for [maasng.wait_for_http_code]
2019-03-01 02:58:46,870 [salt.utils.decorators:613 ][WARNING ][13615] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:58:46,879 [salt.state       :300 ][INFO    ][13615] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-01 02:58:46,879 [salt.state       :1951][INFO    ][13615] Completed state [maasng.wait_for_http_code] at time 02:58:46.879861 duration_in_ms=10.118
2019-03-01 02:58:46,880 [salt.state       :1780][INFO    ][13615] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:58:46.880689
2019-03-01 02:58:46,881 [salt.state       :1813][INFO    ][13615] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-01 02:58:46,881 [salt.state       :300 ][INFO    ][13615] /var/lib/maas/.setup_admin exists
2019-03-01 02:58:46,881 [salt.state       :1951][INFO    ][13615] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:58:46.881668 duration_in_ms=0.98
2019-03-01 02:58:46,882 [salt.state       :1780][INFO    ][13615] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:58:46.882379
2019-03-01 02:58:46,882 [salt.state       :1813][INFO    ][13615] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 02:58:46,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13615] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 02:58:49,007 [salt.state       :300 ][INFO    ][13615] {'pid': 13668, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 02:58:49,008 [salt.state       :1951][INFO    ][13615] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:58:49.008325 duration_in_ms=2125.946
2019-03-01 02:58:49,019 [salt.state       :1780][INFO    ][13615] Running state [maas_region_boot_source_resources_mirror] at time 02:58:49.019725
2019-03-01 02:58:49,020 [salt.state       :1813][INFO    ][13615] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-01 02:58:49,116 [salt.state       :300 ][INFO    ][13615] {'changes': {}}
2019-03-01 02:58:49,117 [salt.state       :1951][INFO    ][13615] Completed state [maas_region_boot_source_resources_mirror] at time 02:58:49.116939 duration_in_ms=97.214
2019-03-01 02:58:49,117 [salt.state       :1780][INFO    ][13615] Running state [maasng.boot_resources_import] at time 02:58:49.117667
2019-03-01 02:58:49,118 [salt.state       :1813][INFO    ][13615] Executing state module.run for [maasng.boot_resources_import]
2019-03-01 02:58:49,118 [salt.utils.decorators:613 ][WARNING ][13615] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:58:49,202 [salt.loaded.ext.module.maasng:1600][INFO    ][13615] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-01 02:58:54,246 [salt.loaded.ext.module.maasng:1600][INFO    ][13615] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-01 02:58:55,796 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025855784984
2019-03-01 02:58:55,821 [salt.minion      :1432][INFO    ][13761] Starting a new job with PID 13761
2019-03-01 02:58:55,862 [salt.minion      :1711][INFO    ][13761] Returning information for job: 20190301025855784984
2019-03-01 02:58:59,334 [salt.state       :300 ][INFO    ][13615] {'ret': True}
2019-03-01 02:58:59,334 [salt.state       :1951][INFO    ][13615] Completed state [maasng.boot_resources_import] at time 02:58:59.334499 duration_in_ms=10216.83
2019-03-01 02:58:59,336 [salt.state       :1780][INFO    ][13615] Running state [maas_region_boot_sources_selection_xenial] at time 02:58:59.336277
2019-03-01 02:58:59,336 [salt.state       :1813][INFO    ][13615] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-01 02:58:59,514 [salt.state       :300 ][INFO    ][13615] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-01 02:58:59,514 [salt.state       :1951][INFO    ][13615] Completed state [maas_region_boot_sources_selection_xenial] at time 02:58:59.514641 duration_in_ms=178.363
2019-03-01 02:58:59,515 [salt.state       :1780][INFO    ][13615] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:58:59.515779
2019-03-01 02:58:59,516 [salt.state       :1813][INFO    ][13615] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-01 02:58:59,516 [salt.utils.decorators:613 ][WARNING ][13615] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:58:59,517 [salt.loaded.ext.module.maasng:1771][INFO    ][13615] boot-sources sync initiated for ALL Rack's
2019-03-01 02:59:00,592 [salt.state       :300 ][INFO    ][13615] {'ret': True}
2019-03-01 02:59:00,592 [salt.state       :1951][INFO    ][13615] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:59:00.592393 duration_in_ms=1076.615
2019-03-01 02:59:00,593 [salt.state       :1780][INFO    ][13615] Running state [maas.process_maas_config] at time 02:59:00.593691
2019-03-01 02:59:00,594 [salt.state       :1813][INFO    ][13615] Executing state module.run for [maas.process_maas_config]
2019-03-01 02:59:00,594 [salt.utils.decorators:613 ][WARNING ][13615] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:59:00,595 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=enable_http_proxy value=True
2019-03-01 02:59:00,652 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=upstream_dns value=8.8.8.8
2019-03-01 02:59:00,706 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=commissioning_distro_series value=xenial
2019-03-01 02:59:01,962 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=default_osystem value=ubuntu
2019-03-01 02:59:02,006 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=active_discovery_interval value=600
2019-03-01 02:59:02,050 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=dnssec_validation value=no
2019-03-01 02:59:02,094 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=maas_name value=mas01
2019-03-01 02:59:02,154 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=network_discovery value=enabled
2019-03-01 02:59:02,249 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=enable_third_party_drivers value=True
2019-03-01 02:59:02,289 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=default_storage_layout value=lvm
2019-03-01 02:59:02,334 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=ntp_external_only value=True
2019-03-01 02:59:02,380 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-01 02:59:02,435 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=default_distro_series value=xenial
2019-03-01 02:59:02,486 [salt.loaded.ext.module.maas:92  ][INFO    ][13615] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-03-01 02:59:02,605 [salt.state       :300 ][INFO    ][13615] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-03-01 02:59:02,605 [salt.state       :1951][INFO    ][13615] Completed state [maas.process_maas_config] at time 02:59:02.605465 duration_in_ms=2011.773
2019-03-01 02:59:02,606 [salt.state       :1780][INFO    ][13615] Running state [pxe_admin] at time 02:59:02.606122
2019-03-01 02:59:02,606 [salt.state       :1813][INFO    ][13615] Executing state maasng.fabric_present for [pxe_admin]
2019-03-01 02:59:02,678 [salt.loaded.ext.module.maasng:945 ][INFO    ][13615] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-01 02:59:02,732 [salt.loaded.ext.module.maasng:1008][WARNING ][13615] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-01 02:59:02,732 [salt.loaded.ext.module.maasng:1011][WARNING ][13615] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-01 02:59:02,791 [salt.state       :300 ][INFO    ][13615] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-01 02:59:02,791 [salt.state       :1951][INFO    ][13615] Completed state [pxe_admin] at time 02:59:02.791472 duration_in_ms=185.35
2019-03-01 02:59:02,791 [salt.state       :1780][INFO    ][13615] Running state [vlan 0] at time 02:59:02.791802
2019-03-01 02:59:02,792 [salt.state       :1813][INFO    ][13615] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-01 02:59:02,832 [salt.loaded.ext.module.maasng:945 ][INFO    ][13615] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-01 02:59:02,926 [salt.loaded.ext.module.maasng:945 ][INFO    ][13615] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', 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'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-03-01 02:59:03,169 [salt.loaded.ext.module.maasng:945 ][INFO    ][13615] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', 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'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-03-01 02:59:03,233 [salt.state       :300 ][INFO    ][13615] {'new': 'Vlan untagged was updated'}
2019-03-01 02:59:03,234 [salt.state       :1951][INFO    ][13615] Completed state [vlan 0] at time 02:59:03.233949 duration_in_ms=442.147
2019-03-01 02:59:03,234 [salt.state       :1780][INFO    ][13615] Running state [192.168.11.0/24] at time 02:59:03.234892
2019-03-01 02:59:03,235 [salt.state       :1813][INFO    ][13615] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-01 02:59:03,389 [salt.loaded.ext.module.maasng:945 ][INFO    ][13615] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', 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'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'7dqyg6', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-01 02:59:03,390 [salt.loaded.ext.module.maasng:1235][WARNING ][13615] Ignoring parameter vlan:0
2019-03-01 02:59:03,456 [salt.state       :300 ][INFO    ][13615] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-01 02:59:03,456 [salt.state       :1951][INFO    ][13615] Completed state [192.168.11.0/24] at time 02:59:03.456764 duration_in_ms=221.871
2019-03-01 02:59:03,457 [salt.state       :1780][INFO    ][13615] Running state [maas_create_iprange_1] at time 02:59:03.457738
2019-03-01 02:59:03,458 [salt.state       :1813][INFO    ][13615] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-01 02:59:03,511 [salt.state       :300 ][INFO    ][13615] Iprange maas_create_iprange_1 already exist.
2019-03-01 02:59:03,511 [salt.state       :1951][INFO    ][13615] Completed state [maas_create_iprange_1] at time 02:59:03.511752 duration_in_ms=54.014
2019-03-01 02:59:03,512 [salt.state       :1780][INFO    ][13615] Running state [vlan 0] at time 02:59:03.512067
2019-03-01 02:59:03,512 [salt.state       :1813][INFO    ][13615] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-01 02:59:03,554 [salt.loaded.ext.module.maasng:945 ][INFO    ][13615] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'7dqyg6', 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'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-03-01 02:59:03,640 [salt.loaded.ext.module.maasng:945 ][INFO    ][13615] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-01 02:59:03,837 [salt.loaded.ext.module.maasng:945 ][INFO    ][13615] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7dqyg6', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-03-01 02:59:03,905 [salt.state       :300 ][INFO    ][13615] {'new': 'Vlan untagged was updated'}
2019-03-01 02:59:03,905 [salt.state       :1951][INFO    ][13615] Completed state [vlan 0] at time 02:59:03.905301 duration_in_ms=393.233
2019-03-01 02:59:03,905 [salt.state       :1780][INFO    ][13615] Running state [opnfv] at time 02:59:03.905894
2019-03-01 02:59:03,906 [salt.state       :1813][INFO    ][13615] Executing state maasng.sshkey_present for [opnfv]
2019-03-01 02:59:03,945 [salt.loaded.ext.module.maasng:1903][INFO    ][13615] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H', u'keysource': u''}]
2019-03-01 02:59:03,945 [salt.state       :300 ][INFO    ][13615] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H already exist for user opnfv.
2019-03-01 02:59:03,946 [salt.state       :1951][INFO    ][13615] Completed state [opnfv] at time 02:59:03.946479 duration_in_ms=40.584
2019-03-01 02:59:03,948 [salt.minion      :1711][INFO    ][13615] Returning information for job: 20190301025840687920
2019-03-01 02:59:04,799 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301025904788994
2019-03-01 02:59:04,812 [salt.minion      :1432][INFO    ][14114] Starting a new job with PID 14114
2019-03-01 02:59:12,612 [salt.state       :915 ][INFO    ][14114] Loading fresh modules for state activity
2019-03-01 02:59:12,720 [salt.state       :1780][INFO    ][14114] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:59:12.720647
2019-03-01 02:59:12,721 [salt.state       :1813][INFO    ][14114] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 02:59:12,724 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14114] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 02:59:14,416 [salt.state       :300 ][INFO    ][14114] {'pid': 14141, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 02:59:14,418 [salt.state       :1951][INFO    ][14114] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:59:14.417944 duration_in_ms=1697.297
2019-03-01 02:59:14,421 [salt.state       :1780][INFO    ][14114] Running state [maas.process_machines] at time 02:59:14.421489
2019-03-01 02:59:14,422 [salt.state       :1813][INFO    ][14114] Executing state module.run for [maas.process_machines]
2019-03-01 02:59:14,423 [salt.utils.decorators:613 ][WARNING ][14114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 02:59:14,930 [salt.loaded.ext.module.maas:412 ][WARNING ][14114] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 02:59:14,932 [salt.loaded.ext.module.maas:92  ][INFO    ][14114] 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=dxgmr4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-01 02:59:16,061 [salt.loaded.ext.module.maas:412 ][WARNING ][14114] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 02:59:16,061 [salt.loaded.ext.module.maas:92  ][INFO    ][14114] 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=kmbppf architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-01 02:59:17,171 [salt.loaded.ext.module.maas:412 ][WARNING ][14114] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 02:59:17,172 [salt.loaded.ext.module.maas:92  ][INFO    ][14114] 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=gaaxag architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-01 02:59:18,208 [salt.loaded.ext.module.maas:412 ][WARNING ][14114] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 02:59:18,209 [salt.loaded.ext.module.maas:92  ][INFO    ][14114] 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=xmyhdx architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-01 02:59:19,337 [salt.loaded.ext.module.maas:412 ][WARNING ][14114] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-01 02:59:19,337 [salt.loaded.ext.module.maas:92  ][INFO    ][14114] 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=mgy7tc architecture=amd64/generic power_parameters_power_user=opnfv
2019-03-01 02:59:19,903 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301025919893189
2019-03-01 02:59:19,927 [salt.minion      :1432][INFO    ][14352] Starting a new job with PID 14352
2019-03-01 02:59:19,956 [salt.minion      :1711][INFO    ][14352] Returning information for job: 20190301025919893189
2019-03-01 02:59:20,413 [salt.state       :300 ][INFO    ][14114] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-03-01 02:59:20,413 [salt.state       :1951][INFO    ][14114] Completed state [maas.process_machines] at time 02:59:20.413780 duration_in_ms=5992.291
2019-03-01 02:59:20,418 [salt.minion      :1711][INFO    ][14114] Returning information for job: 20190301025904788994
2019-03-01 02:59:53,330 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301025953323275
2019-03-01 02:59:53,348 [salt.minion      :1432][INFO    ][14425] Starting a new job with PID 14425
2019-03-01 03:00:01,519 [salt.state       :915 ][INFO    ][14425] Loading fresh modules for state activity
2019-03-01 03:00:01,617 [salt.state       :1780][INFO    ][14425] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:00:01.617776
2019-03-01 03:00:01,618 [salt.state       :1813][INFO    ][14425] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 03:00:01,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14425] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 03:00:03,407 [salt.state       :300 ][INFO    ][14425] {'pid': 14448, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 03:00:03,407 [salt.state       :1951][INFO    ][14425] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:00:03.407597 duration_in_ms=1789.822
2019-03-01 03:00:03,409 [salt.state       :1780][INFO    ][14425] Running state [maas.wait_for_machine_status] at time 03:00:03.408955
2019-03-01 03:00:03,409 [salt.state       :1813][INFO    ][14425] Executing state module.run for [maas.wait_for_machine_status]
2019-03-01 03:00:03,409 [salt.utils.decorators:613 ][WARNING ][14425] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 03:00:06,079 [salt.state       :300 ][INFO    ][14425] {'ret': True}
2019-03-01 03:00:06,079 [salt.state       :1951][INFO    ][14425] Completed state [maas.wait_for_machine_status] at time 03:00:06.079672 duration_in_ms=2670.716
2019-03-01 03:00:06,081 [salt.minion      :1711][INFO    ][14425] Returning information for job: 20190301025953323275
2019-03-01 03:00:06,684 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301030006634472
2019-03-01 03:00:06,704 [salt.minion      :1432][INFO    ][14464] Starting a new job with PID 14464
2019-03-01 03:00:07,835 [salt.state       :915 ][INFO    ][14464] Loading fresh modules for state activity
2019-03-01 03:00:07,980 [salt.state       :1780][INFO    ][14464] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:00:07.980301
2019-03-01 03:00:07,980 [salt.state       :1813][INFO    ][14464] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 03:00:07,982 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14464] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 03:00:09,799 [salt.state       :300 ][INFO    ][14464] {'pid': 14471, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 03:00:09,800 [salt.state       :1951][INFO    ][14464] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:00:09.800075 duration_in_ms=1819.774
2019-03-01 03:00:09,801 [salt.state       :1780][INFO    ][14464] Running state [maas_machines_storage_cmp002_lvm] at time 03:00:09.801702
2019-03-01 03:00:09,802 [salt.state       :1813][INFO    ][14464] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-01 03:00:10,319 [salt.state       :300 ][INFO    ][14464] Machine cmp002 is not in Ready state.
2019-03-01 03:00:10,320 [salt.state       :1951][INFO    ][14464] Completed state [maas_machines_storage_cmp002_lvm] at time 03:00:10.320205 duration_in_ms=518.501
2019-03-01 03:00:10,320 [salt.state       :1780][INFO    ][14464] Running state [maas_machines_storage_cmp001_lvm] at time 03:00:10.320806
2019-03-01 03:00:10,321 [salt.state       :1813][INFO    ][14464] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-01 03:00:10,846 [salt.state       :300 ][INFO    ][14464] Machine cmp001 is not in Ready state.
2019-03-01 03:00:10,846 [salt.state       :1951][INFO    ][14464] Completed state [maas_machines_storage_cmp001_lvm] at time 03:00:10.846750 duration_in_ms=525.944
2019-03-01 03:00:10,854 [salt.minion      :1711][INFO    ][14464] Returning information for job: 20190301030006634472
2019-03-01 03:00:11,397 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301030011386519
2019-03-01 03:00:11,418 [salt.minion      :1432][INFO    ][14481] Starting a new job with PID 14481
2019-03-01 03:00:12,625 [salt.state       :915 ][INFO    ][14481] Loading fresh modules for state activity
2019-03-01 03:00:12,719 [salt.state       :1780][INFO    ][14481] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:00:12.719640
2019-03-01 03:00:12,719 [salt.state       :1813][INFO    ][14481] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 03:00:12,721 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14481] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 03:00:14,532 [salt.state       :300 ][INFO    ][14481] {'pid': 14488, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 03:00:14,533 [salt.state       :1951][INFO    ][14481] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:00:14.533667 duration_in_ms=1814.026
2019-03-01 03:00:14,537 [salt.state       :1780][INFO    ][14481] Running state [maas.deploy_machines] at time 03:00:14.537244
2019-03-01 03:00:14,537 [salt.state       :1813][INFO    ][14481] Executing state module.run for [maas.deploy_machines]
2019-03-01 03:00:14,538 [salt.utils.decorators:613 ][WARNING ][14481] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 03:00:15,089 [salt.loaded.ext.module.maas:684 ][INFO    ][14481] deploymachines hwe_kernel=ga-16.04 system_id=mgy7tc distro_series=xenial
2019-03-01 03:00:17,306 [salt.state       :300 ][INFO    ][14481] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-03-01 03:00:17,310 [salt.state       :1951][INFO    ][14481] Completed state [maas.deploy_machines] at time 03:00:17.310035 duration_in_ms=2772.79
2019-03-01 03:00:17,312 [salt.minion      :1711][INFO    ][14481] Returning information for job: 20190301030011386519
2019-03-01 03:00:17,864 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command state.apply with jid 20190301030017852521
2019-03-01 03:00:17,887 [salt.minion      :1432][INFO    ][14549] Starting a new job with PID 14549
2019-03-01 03:00:25,801 [salt.state       :915 ][INFO    ][14549] Loading fresh modules for state activity
2019-03-01 03:00:25,893 [salt.state       :1780][INFO    ][14549] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:00:25.893648
2019-03-01 03:00:25,893 [salt.state       :1813][INFO    ][14549] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-01 03:00:25,896 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14549] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-01 03:00:27,567 [salt.state       :300 ][INFO    ][14549] {'pid': 14565, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-01 03:00:27,568 [salt.state       :1951][INFO    ][14549] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:00:27.568430 duration_in_ms=1674.782
2019-03-01 03:00:27,570 [salt.state       :1780][INFO    ][14549] Running state [maas.wait_for_machine_status] at time 03:00:27.570890
2019-03-01 03:00:27,571 [salt.state       :1813][INFO    ][14549] Executing state module.run for [maas.wait_for_machine_status]
2019-03-01 03:00:27,572 [salt.utils.decorators:613 ][WARNING ][14549] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-01 03:00:30,176 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.40731502s left)
2019-03-01 03:00:32,986 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030032973290
2019-03-01 03:00:33,007 [salt.minion      :1432][INFO    ][14594] Starting a new job with PID 14594
2019-03-01 03:00:33,032 [salt.minion      :1711][INFO    ][14594] Returning information for job: 20190301030032973290
2019-03-01 03:01:02,877 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.70654917s left)
2019-03-01 03:01:03,043 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030103034235
2019-03-01 03:01:03,061 [salt.minion      :1432][INFO    ][14685] Starting a new job with PID 14685
2019-03-01 03:01:03,090 [salt.minion      :1711][INFO    ][14685] Returning information for job: 20190301030103034235
2019-03-01 03:01:33,133 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030133121164
2019-03-01 03:01:33,155 [salt.minion      :1432][INFO    ][14712] Starting a new job with PID 14712
2019-03-01 03:01:33,184 [salt.minion      :1711][INFO    ][14712] Returning information for job: 20190301030133121164
2019-03-01 03:01:35,753 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.83014894s left)
2019-03-01 03:02:03,188 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030203177426
2019-03-01 03:02:03,204 [salt.minion      :1432][INFO    ][14785] Starting a new job with PID 14785
2019-03-01 03:02:03,228 [salt.minion      :1711][INFO    ][14785] Returning information for job: 20190301030203177426
2019-03-01 03:02:08,419 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2149.16443896s left)
2019-03-01 03:02:33,240 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030233228240
2019-03-01 03:02:33,263 [salt.minion      :1432][INFO    ][14814] Starting a new job with PID 14814
2019-03-01 03:02:33,294 [salt.minion      :1711][INFO    ][14814] Returning information for job: 20190301030233228240
2019-03-01 03:02:41,012 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2116.572016s left)
2019-03-01 03:03:03,321 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030303306381
2019-03-01 03:03:03,345 [salt.minion      :1432][INFO    ][14887] Starting a new job with PID 14887
2019-03-01 03:03:03,371 [salt.minion      :1711][INFO    ][14887] Returning information for job: 20190301030303306381
2019-03-01 03:03:13,694 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2083.88959694s left)
2019-03-01 03:03:33,397 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030333379522
2019-03-01 03:03:33,418 [salt.minion      :1432][INFO    ][14916] Starting a new job with PID 14916
2019-03-01 03:03:33,449 [salt.minion      :1711][INFO    ][14916] Returning information for job: 20190301030333379522
2019-03-01 03:03:46,294 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2051.28935909s left)
2019-03-01 03:04:03,485 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030403476258
2019-03-01 03:04:03,506 [salt.minion      :1432][INFO    ][14992] Starting a new job with PID 14992
2019-03-01 03:04:03,529 [salt.minion      :1711][INFO    ][14992] Returning information for job: 20190301030403476258
2019-03-01 03:04:18,985 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2018.59805107s left)
2019-03-01 03:04:33,681 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030433673969
2019-03-01 03:04:33,695 [salt.minion      :1432][INFO    ][15044] Starting a new job with PID 15044
2019-03-01 03:04:33,720 [salt.minion      :1711][INFO    ][15044] Returning information for job: 20190301030433673969
2019-03-01 03:04:51,745 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1985.83825016s left)
2019-03-01 03:05:03,884 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030503873419
2019-03-01 03:05:03,908 [salt.minion      :1432][INFO    ][15148] Starting a new job with PID 15148
2019-03-01 03:05:03,934 [salt.minion      :1711][INFO    ][15148] Returning information for job: 20190301030503873419
2019-03-01 03:05:24,461 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1953.12293506s left)
2019-03-01 03:05:33,947 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030533934769
2019-03-01 03:05:33,968 [salt.minion      :1432][INFO    ][15179] Starting a new job with PID 15179
2019-03-01 03:05:33,998 [salt.minion      :1711][INFO    ][15179] Returning information for job: 20190301030533934769
2019-03-01 03:05:57,028 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1920.55555606s left)
2019-03-01 03:06:03,970 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030603963326
2019-03-01 03:06:03,991 [salt.minion      :1432][INFO    ][15371] Starting a new job with PID 15371
2019-03-01 03:06:04,017 [salt.minion      :1711][INFO    ][15371] Returning information for job: 20190301030603963326
2019-03-01 03:06:29,837 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1887.74620795s left)
2019-03-01 03:06:34,034 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030634022794
2019-03-01 03:06:34,055 [salt.minion      :1432][INFO    ][15402] Starting a new job with PID 15402
2019-03-01 03:06:34,080 [salt.minion      :1711][INFO    ][15402] Returning information for job: 20190301030634022794
2019-03-01 03:07:02,664 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1854.92116499s left)
2019-03-01 03:07:04,164 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030704149314
2019-03-01 03:07:04,187 [salt.minion      :1432][INFO    ][15526] Starting a new job with PID 15526
2019-03-01 03:07:04,213 [salt.minion      :1711][INFO    ][15526] Returning information for job: 20190301030704149314
2019-03-01 03:07:34,202 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030734194996
2019-03-01 03:07:34,221 [salt.minion      :1432][INFO    ][15553] Starting a new job with PID 15553
2019-03-01 03:07:34,247 [salt.minion      :1711][INFO    ][15553] Returning information for job: 20190301030734194996
2019-03-01 03:07:35,322 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1822.26203299s left)
2019-03-01 03:08:04,338 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030804325131
2019-03-01 03:08:04,356 [salt.minion      :1432][INFO    ][15647] Starting a new job with PID 15647
2019-03-01 03:08:04,381 [salt.minion      :1711][INFO    ][15647] Returning information for job: 20190301030804325131
2019-03-01 03:08:08,237 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1789.34605002s left)
2019-03-01 03:08:34,465 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030834457490
2019-03-01 03:08:34,478 [salt.minion      :1432][INFO    ][15687] Starting a new job with PID 15687
2019-03-01 03:08:34,505 [salt.minion      :1711][INFO    ][15687] Returning information for job: 20190301030834457490
2019-03-01 03:08:41,058 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1756.52551413s left)
2019-03-01 03:09:04,537 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030904526105
2019-03-01 03:09:04,557 [salt.minion      :1432][INFO    ][15852] Starting a new job with PID 15852
2019-03-01 03:09:04,582 [salt.minion      :1711][INFO    ][15852] Returning information for job: 20190301030904526105
2019-03-01 03:09:13,665 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1723.91904211s left)
2019-03-01 03:09:34,687 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301030934675358
2019-03-01 03:09:34,708 [salt.minion      :1432][INFO    ][15881] Starting a new job with PID 15881
2019-03-01 03:09:34,733 [salt.minion      :1711][INFO    ][15881] Returning information for job: 20190301030934675358
2019-03-01 03:09:46,152 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1691.43166614s left)
2019-03-01 03:10:04,817 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301031004805513
2019-03-01 03:10:04,836 [salt.minion      :1432][INFO    ][15955] Starting a new job with PID 15955
2019-03-01 03:10:04,860 [salt.minion      :1711][INFO    ][15955] Returning information for job: 20190301031004805513
2019-03-01 03:10:19,032 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1658.55112004s left)
2019-03-01 03:10:34,985 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301031034964914
2019-03-01 03:10:35,004 [salt.minion      :1432][INFO    ][15989] Starting a new job with PID 15989
2019-03-01 03:10:35,034 [salt.minion      :1711][INFO    ][15989] Returning information for job: 20190301031034964914
2019-03-01 03:10:39,415 [salt.utils.schedule:1377][INFO    ][2894] Running scheduled job: __mine_interval
2019-03-01 03:10:51,862 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1625.72130609s left)
2019-03-01 03:11:05,110 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301031105098590
2019-03-01 03:11:05,131 [salt.minion      :1432][INFO    ][16234] Starting a new job with PID 16234
2019-03-01 03:11:05,159 [salt.minion      :1711][INFO    ][16234] Returning information for job: 20190301031105098590
2019-03-01 03:11:24,767 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1592.81612206s left)
2019-03-01 03:11:35,289 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301031135280080
2019-03-01 03:11:35,313 [salt.minion      :1432][INFO    ][16265] Starting a new job with PID 16265
2019-03-01 03:11:35,343 [salt.minion      :1711][INFO    ][16265] Returning information for job: 20190301031135280080
2019-03-01 03:11:57,469 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1560.11502695s left)
2019-03-01 03:12:05,469 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301031205461884
2019-03-01 03:12:05,491 [salt.minion      :1432][INFO    ][16338] Starting a new job with PID 16338
2019-03-01 03:12:05,518 [salt.minion      :1711][INFO    ][16338] Returning information for job: 20190301031205461884
2019-03-01 03:12:30,127 [salt.loaded.ext.module.maas:1023][INFO    ][14549] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1527.45695996s left)
2019-03-01 03:12:35,661 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190301031235653974
2019-03-01 03:12:35,677 [salt.minion      :1432][INFO    ][16369] Starting a new job with PID 16369
2019-03-01 03:12:35,702 [salt.minion      :1711][INFO    ][16369] Returning information for job: 20190301031235653974
2019-03-01 03:13:02,689 [salt.state       :300 ][INFO    ][14549] {'ret': True}
2019-03-01 03:13:02,690 [salt.state       :1951][INFO    ][14549] Completed state [maas.wait_for_machine_status] at time 03:13:02.690087 duration_in_ms=755119.196
2019-03-01 03:13:02,694 [salt.minion      :1711][INFO    ][14549] Returning information for job: 20190301030017852521
2019-03-01 04:10:39,415 [salt.utils.schedule:1377][INFO    ][2894] Running scheduled job: __mine_interval
2019-03-01 04:30:09,919 [salt.minion      :1308][INFO    ][2894] User sudo_ubuntu Executing command cp.push_dir with jid 20190301043009907344
2019-03-01 04:30:09,937 [salt.minion      :1432][INFO    ][24590] Starting a new job with PID 24590
