2019-05-30 20:10:18,060 [salt.utils.decorators:613 ][WARNING ][2148] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 20:10:19,173 [salt.utils.decorators:613 ][WARNING ][2148] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 20:10:22,764 [salt.loaded.int.states.file:2298][WARNING ][2320] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-30 20:10:36,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2621] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-30 20:10:36,953 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2621] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-30 20:10:36,997 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-05-30 20:10:38,144 [salt.cli.daemons :293 ][INFO    ][2732] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-05-30 20:10:38,328 [salt.cli.daemons :82  ][INFO    ][2732] Starting up the Salt Minion
2019-05-30 20:10:38,329 [salt.utils.event :1017][INFO    ][2732] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-05-30 20:10:39,596 [salt.minion      :976 ][INFO    ][2732] Creating minion process manager
2019-05-30 20:10:41,564 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][2732] Executing command ['date', '+%z'] in directory '/root'
2019-05-30 20:10:41,588 [salt.utils.schedule:568 ][INFO    ][2732] Updating job settings for scheduled job: __mine_interval
2019-05-30 20:10:41,590 [salt.minion      :1108][INFO    ][2732] Added mine.update to scheduler
2019-05-30 20:10:41,596 [salt.minion      :1975][INFO    ][2732] Minion is starting as user 'root'
2019-05-30 20:10:41,609 [salt.minion      :2336][INFO    ][2732] Minion is ready to receive requests!
2019-05-30 20:10:45,511 [salt.state       :2022][WARNING ][2624] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-30 20:10:48,744 [salt.utils.decorators:613 ][WARNING ][2624] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 20:10:49,085 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201049071988
2019-05-30 20:10:49,120 [salt.minion      :1432][INFO    ][3098] Starting a new job with PID 3098
2019-05-30 20:10:49,169 [salt.minion      :1711][INFO    ][3098] Returning information for job: 20190530201049071988
2019-05-30 20:11:19,252 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201119235863
2019-05-30 20:11:19,279 [salt.minion      :1432][INFO    ][3331] Starting a new job with PID 3331
2019-05-30 20:11:19,307 [salt.minion      :1711][INFO    ][3331] Returning information for job: 20190530201119235863
2019-05-30 20:11:49,374 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201149363357
2019-05-30 20:11:49,398 [salt.minion      :1432][INFO    ][3516] Starting a new job with PID 3516
2019-05-30 20:11:49,426 [salt.minion      :1711][INFO    ][3516] Returning information for job: 20190530201149363357
2019-05-30 20:12:19,468 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201219454292
2019-05-30 20:12:19,495 [salt.minion      :1432][INFO    ][3704] Starting a new job with PID 3704
2019-05-30 20:12:19,523 [salt.minion      :1711][INFO    ][3704] Returning information for job: 20190530201219454292
2019-05-30 20:12:41,974 [salt.utils.decorators:613 ][WARNING ][2624] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 20:12:49,572 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201249560248
2019-05-30 20:12:49,600 [salt.minion      :1432][INFO    ][4052] Starting a new job with PID 4052
2019-05-30 20:12:49,639 [salt.minion      :1711][INFO    ][4052] Returning information for job: 20190530201249560248
2019-05-30 20:13:19,664 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201319652612
2019-05-30 20:13:19,687 [salt.minion      :1432][INFO    ][4143] Starting a new job with PID 4143
2019-05-30 20:13:19,723 [salt.minion      :1711][INFO    ][4143] Returning information for job: 20190530201319652612
2019-05-30 20:13:49,772 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201349756549
2019-05-30 20:13:49,797 [salt.minion      :1432][INFO    ][4204] Starting a new job with PID 4204
2019-05-30 20:13:49,826 [salt.minion      :1711][INFO    ][4204] Returning information for job: 20190530201349756549
2019-05-30 20:14:19,868 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201419852155
2019-05-30 20:14:19,895 [salt.minion      :1432][INFO    ][4240] Starting a new job with PID 4240
2019-05-30 20:14:19,927 [salt.minion      :1711][INFO    ][4240] Returning information for job: 20190530201419852155
2019-05-30 20:14:49,993 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201449983926
2019-05-30 20:14:50,017 [salt.minion      :1432][INFO    ][4289] Starting a new job with PID 4289
2019-05-30 20:14:50,049 [salt.minion      :1711][INFO    ][4289] Returning information for job: 20190530201449983926
2019-05-30 20:15:20,099 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201520085564
2019-05-30 20:15:20,128 [salt.minion      :1432][INFO    ][4325] Starting a new job with PID 4325
2019-05-30 20:15:20,161 [salt.minion      :1711][INFO    ][4325] Returning information for job: 20190530201520085564
2019-05-30 20:15:50,237 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201550224682
2019-05-30 20:15:50,262 [salt.minion      :1432][INFO    ][4372] Starting a new job with PID 4372
2019-05-30 20:15:50,291 [salt.minion      :1711][INFO    ][4372] Returning information for job: 20190530201550224682
2019-05-30 20:16:20,347 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201620335143
2019-05-30 20:16:20,374 [salt.minion      :1432][INFO    ][4411] Starting a new job with PID 4411
2019-05-30 20:16:20,401 [salt.minion      :1711][INFO    ][4411] Returning information for job: 20190530201620335143
2019-05-30 20:16:50,465 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201650451807
2019-05-30 20:16:50,495 [salt.minion      :1432][INFO    ][4465] Starting a new job with PID 4465
2019-05-30 20:16:50,528 [salt.minion      :1711][INFO    ][4465] Returning information for job: 20190530201650451807
2019-05-30 20:17:20,608 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201720593335
2019-05-30 20:17:20,633 [salt.minion      :1432][INFO    ][4506] Starting a new job with PID 4506
2019-05-30 20:17:20,665 [salt.minion      :1711][INFO    ][4506] Returning information for job: 20190530201720593335
2019-05-30 20:17:50,757 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201750746532
2019-05-30 20:17:50,783 [salt.minion      :1432][INFO    ][4554] Starting a new job with PID 4554
2019-05-30 20:17:50,815 [salt.minion      :1711][INFO    ][4554] Returning information for job: 20190530201750746532
2019-05-30 20:18:20,908 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201820898261
2019-05-30 20:18:20,935 [salt.minion      :1432][INFO    ][4593] Starting a new job with PID 4593
2019-05-30 20:18:20,968 [salt.minion      :1711][INFO    ][4593] Returning information for job: 20190530201820898261
2019-05-30 20:18:51,061 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201851048314
2019-05-30 20:18:51,088 [salt.minion      :1432][INFO    ][4637] Starting a new job with PID 4637
2019-05-30 20:18:51,117 [salt.minion      :1711][INFO    ][4637] Returning information for job: 20190530201851048314
2019-05-30 20:19:21,231 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201921216310
2019-05-30 20:19:21,259 [salt.minion      :1432][INFO    ][4677] Starting a new job with PID 4677
2019-05-30 20:19:21,288 [salt.minion      :1711][INFO    ][4677] Returning information for job: 20190530201921216310
2019-05-30 20:19:51,420 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530201951396467
2019-05-30 20:19:51,447 [salt.minion      :1432][INFO    ][4720] Starting a new job with PID 4720
2019-05-30 20:19:51,478 [salt.minion      :1711][INFO    ][4720] Returning information for job: 20190530201951396467
2019-05-30 20:20:21,612 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202021596206
2019-05-30 20:20:21,643 [salt.minion      :1432][INFO    ][4759] Starting a new job with PID 4759
2019-05-30 20:20:21,672 [salt.minion      :1711][INFO    ][4759] Returning information for job: 20190530202021596206
2019-05-30 20:20:51,785 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202051767656
2019-05-30 20:20:51,812 [salt.minion      :1432][INFO    ][4821] Starting a new job with PID 4821
2019-05-30 20:20:51,840 [salt.minion      :1711][INFO    ][4821] Returning information for job: 20190530202051767656
2019-05-30 20:21:21,979 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202121962922
2019-05-30 20:21:22,006 [salt.minion      :1432][INFO    ][4876] Starting a new job with PID 4876
2019-05-30 20:21:22,037 [salt.minion      :1711][INFO    ][4876] Returning information for job: 20190530202121962922
2019-05-30 20:21:52,155 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202152139550
2019-05-30 20:21:52,180 [salt.minion      :1432][INFO    ][4918] Starting a new job with PID 4918
2019-05-30 20:21:52,212 [salt.minion      :1711][INFO    ][4918] Returning information for job: 20190530202152139550
2019-05-30 20:22:22,363 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202222350801
2019-05-30 20:22:22,391 [salt.minion      :1432][INFO    ][4955] Starting a new job with PID 4955
2019-05-30 20:22:22,420 [salt.minion      :1711][INFO    ][4955] Returning information for job: 20190530202222350801
2019-05-30 20:22:52,555 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202252544483
2019-05-30 20:22:52,587 [salt.minion      :1432][INFO    ][5000] Starting a new job with PID 5000
2019-05-30 20:22:52,619 [salt.minion      :1711][INFO    ][5000] Returning information for job: 20190530202252544483
2019-05-30 20:23:22,578 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202322564841
2019-05-30 20:23:22,604 [salt.minion      :1432][INFO    ][5038] Starting a new job with PID 5038
2019-05-30 20:23:22,633 [salt.minion      :1711][INFO    ][5038] Returning information for job: 20190530202322564841
2019-05-30 20:23:52,593 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202352582322
2019-05-30 20:23:52,618 [salt.minion      :1432][INFO    ][5081] Starting a new job with PID 5081
2019-05-30 20:23:52,647 [salt.minion      :1711][INFO    ][5081] Returning information for job: 20190530202352582322
2019-05-30 20:24:22,627 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202422608232
2019-05-30 20:24:22,657 [salt.minion      :1432][INFO    ][5122] Starting a new job with PID 5122
2019-05-30 20:24:22,691 [salt.minion      :1711][INFO    ][5122] Returning information for job: 20190530202422608232
2019-05-30 20:24:52,696 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202452676954
2019-05-30 20:24:52,724 [salt.minion      :1432][INFO    ][5164] Starting a new job with PID 5164
2019-05-30 20:24:52,757 [salt.minion      :1711][INFO    ][5164] Returning information for job: 20190530202452676954
2019-05-30 20:25:22,771 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202522758407
2019-05-30 20:25:22,798 [salt.minion      :1432][INFO    ][5203] Starting a new job with PID 5203
2019-05-30 20:25:22,831 [salt.minion      :1711][INFO    ][5203] Returning information for job: 20190530202522758407
2019-05-30 20:25:52,867 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202552851746
2019-05-30 20:25:52,894 [salt.minion      :1432][INFO    ][5245] Starting a new job with PID 5245
2019-05-30 20:25:52,928 [salt.minion      :1711][INFO    ][5245] Returning information for job: 20190530202552851746
2019-05-30 20:26:22,965 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202622948753
2019-05-30 20:26:22,991 [salt.minion      :1432][INFO    ][5283] Starting a new job with PID 5283
2019-05-30 20:26:23,030 [salt.minion      :1711][INFO    ][5283] Returning information for job: 20190530202622948753
2019-05-30 20:26:53,081 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202653067431
2019-05-30 20:26:53,111 [salt.minion      :1432][INFO    ][5326] Starting a new job with PID 5326
2019-05-30 20:26:53,142 [salt.minion      :1711][INFO    ][5326] Returning information for job: 20190530202653067431
2019-05-30 20:27:23,195 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202723180962
2019-05-30 20:27:23,220 [salt.minion      :1432][INFO    ][5367] Starting a new job with PID 5367
2019-05-30 20:27:23,251 [salt.minion      :1711][INFO    ][5367] Returning information for job: 20190530202723180962
2019-05-30 20:27:53,326 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202753310597
2019-05-30 20:27:53,359 [salt.minion      :1432][INFO    ][5409] Starting a new job with PID 5409
2019-05-30 20:27:53,388 [salt.minion      :1711][INFO    ][5409] Returning information for job: 20190530202753310597
2019-05-30 20:28:12,855 [salt.utils.decorators:613 ][WARNING ][2624] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 20:28:23,471 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202823452360
2019-05-30 20:28:23,495 [salt.minion      :1432][INFO    ][5463] Starting a new job with PID 5463
2019-05-30 20:28:23,524 [salt.minion      :1711][INFO    ][5463] Returning information for job: 20190530202823452360
2019-05-30 20:28:53,622 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202853609322
2019-05-30 20:28:53,651 [salt.minion      :1432][INFO    ][5511] Starting a new job with PID 5511
2019-05-30 20:28:53,683 [salt.minion      :1711][INFO    ][5511] Returning information for job: 20190530202853609322
2019-05-30 20:29:23,813 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202923795937
2019-05-30 20:29:23,842 [salt.minion      :1432][INFO    ][5555] Starting a new job with PID 5555
2019-05-30 20:29:23,869 [salt.minion      :1711][INFO    ][5555] Returning information for job: 20190530202923795937
2019-05-30 20:29:53,995 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530202953979355
2019-05-30 20:29:54,024 [salt.minion      :1432][INFO    ][5603] Starting a new job with PID 5603
2019-05-30 20:29:54,056 [salt.minion      :1711][INFO    ][5603] Returning information for job: 20190530202953979355
2019-05-30 20:30:24,173 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203024164194
2019-05-30 20:30:24,197 [salt.minion      :1432][INFO    ][5650] Starting a new job with PID 5650
2019-05-30 20:30:24,227 [salt.minion      :1711][INFO    ][5650] Returning information for job: 20190530203024164194
2019-05-30 20:30:54,368 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203054353410
2019-05-30 20:30:54,394 [salt.minion      :1432][INFO    ][5713] Starting a new job with PID 5713
2019-05-30 20:30:54,426 [salt.minion      :1711][INFO    ][5713] Returning information for job: 20190530203054353410
2019-05-30 20:31:24,401 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203124382930
2019-05-30 20:31:24,427 [salt.minion      :1432][INFO    ][5762] Starting a new job with PID 5762
2019-05-30 20:31:24,459 [salt.minion      :1711][INFO    ][5762] Returning information for job: 20190530203124382930
2019-05-30 20:31:54,623 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203154605978
2019-05-30 20:31:54,655 [salt.minion      :1432][INFO    ][5813] Starting a new job with PID 5813
2019-05-30 20:31:54,682 [salt.minion      :1711][INFO    ][5813] Returning information for job: 20190530203154605978
2019-05-30 20:32:24,680 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203224667075
2019-05-30 20:32:24,708 [salt.minion      :1432][INFO    ][5855] Starting a new job with PID 5855
2019-05-30 20:32:24,740 [salt.minion      :1711][INFO    ][5855] Returning information for job: 20190530203224667075
2019-05-30 20:32:54,715 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203254703332
2019-05-30 20:32:54,732 [salt.minion      :1432][INFO    ][5913] Starting a new job with PID 5913
2019-05-30 20:32:54,763 [salt.minion      :1711][INFO    ][5913] Returning information for job: 20190530203254703332
2019-05-30 20:33:21,092 [salt.utils.decorators:613 ][WARNING ][2624] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 20:33:24,739 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203324727021
2019-05-30 20:33:24,762 [salt.minion      :1432][INFO    ][6125] Starting a new job with PID 6125
2019-05-30 20:33:24,799 [salt.minion      :1711][INFO    ][6125] Returning information for job: 20190530203324727021
2019-05-30 20:33:25,026 [salt.loaded.ext.module.maasng:1008][WARNING ][2624] Detected cidr:192.168.11.0/24 in fabric:fabric-3
2019-05-30 20:33:25,027 [salt.loaded.ext.module.maasng:1011][WARNING ][2624] Guessing, that fabric with current name:fabric-3
 should be renamed to:pxe_admin
2019-05-30 20:33:25,755 [salt.loaded.ext.module.maasng:1235][WARNING ][2624] Ignoring parameter vlan:0
2019-05-30 20:33:27,584 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530203327572879
2019-05-30 20:33:27,606 [salt.minion      :1432][INFO    ][6177] Starting a new job with PID 6177
2019-05-30 20:33:33,810 [salt.state       :915 ][INFO    ][6177] Loading fresh modules for state activity
2019-05-30 20:33:33,873 [salt.fileclient  :1219][INFO    ][6177] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-05-30 20:33:33,925 [salt.state       :1780][INFO    ][6177] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:33:33.925239
2019-05-30 20:33:33,925 [salt.state       :1813][INFO    ][6177] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 20:33:33,928 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6177] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 20:33:35,878 [salt.state       :300 ][INFO    ][6177] {'pid': 6206, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 20:33:35,879 [salt.state       :1951][INFO    ][6177] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:33:35.879085 duration_in_ms=1953.845
2019-05-30 20:33:35,884 [salt.state       :1780][INFO    ][6177] Running state [maas.process_machines] at time 20:33:35.883889
2019-05-30 20:33:35,884 [salt.state       :1813][INFO    ][6177] Executing state module.run for [maas.process_machines]
2019-05-30 20:33:35,886 [salt.utils.decorators:613 ][WARNING ][6177] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 20:33:35,960 [salt.loaded.ext.module.maas:412 ][WARNING ][6177] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 20:33:35,960 [salt.loaded.ext.module.maas:92  ][INFO    ][6177] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 20:33:37,581 [salt.loaded.ext.module.maas:412 ][WARNING ][6177] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 20:33:37,582 [salt.loaded.ext.module.maas:92  ][INFO    ][6177] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 20:33:39,000 [salt.loaded.ext.module.maas:412 ][WARNING ][6177] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 20:33:39,000 [salt.loaded.ext.module.maas:92  ][INFO    ][6177] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 20:33:40,355 [salt.loaded.ext.module.maas:412 ][WARNING ][6177] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 20:33:40,356 [salt.loaded.ext.module.maas:92  ][INFO    ][6177] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 20:33:41,681 [salt.loaded.ext.module.maas:412 ][WARNING ][6177] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 20:33:41,681 [salt.loaded.ext.module.maas:92  ][INFO    ][6177] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 20:33:42,679 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203342670506
2019-05-30 20:33:42,702 [salt.minion      :1432][INFO    ][6480] Starting a new job with PID 6480
2019-05-30 20:33:42,729 [salt.minion      :1711][INFO    ][6480] Returning information for job: 20190530203342670506
2019-05-30 20:33:43,078 [salt.state       :300 ][INFO    ][6177] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-30 20:33:43,078 [salt.state       :1951][INFO    ][6177] Completed state [maas.process_machines] at time 20:33:43.078875 duration_in_ms=7194.986
2019-05-30 20:33:43,081 [salt.minion      :1711][INFO    ][6177] Returning information for job: 20190530203327572879
2019-05-30 20:34:14,539 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530203414530354
2019-05-30 20:34:14,567 [salt.minion      :1432][INFO    ][6534] Starting a new job with PID 6534
2019-05-30 20:34:20,611 [salt.state       :915 ][INFO    ][6534] Loading fresh modules for state activity
2019-05-30 20:34:20,692 [salt.fileclient  :1219][INFO    ][6534] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-30 20:34:20,751 [salt.state       :1780][INFO    ][6534] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:34:20.751428
2019-05-30 20:34:20,751 [salt.state       :1813][INFO    ][6534] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 20:34:20,754 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6534] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 20:34:22,717 [salt.state       :300 ][INFO    ][6534] {'pid': 6558, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 20:34:22,719 [salt.state       :1951][INFO    ][6534] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:34:22.719321 duration_in_ms=1967.893
2019-05-30 20:34:22,721 [salt.state       :1780][INFO    ][6534] Running state [maas.wait_for_machine_status] at time 20:34:22.721816
2019-05-30 20:34:22,722 [salt.state       :1813][INFO    ][6534] Executing state module.run for [maas.wait_for_machine_status]
2019-05-30 20:34:22,722 [salt.utils.decorators:613 ][WARNING ][6534] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 20:34:23,602 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.13327098s left)
2019-05-30 20:34:29,577 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203429560636
2019-05-30 20:34:29,608 [salt.minion      :1432][INFO    ][6570] Starting a new job with PID 6570
2019-05-30 20:34:29,639 [salt.minion      :1711][INFO    ][6570] Returning information for job: 20190530203429560636
2019-05-30 20:34:54,479 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.25652814s left)
2019-05-30 20:34:59,659 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203459646706
2019-05-30 20:34:59,687 [salt.minion      :1432][INFO    ][6610] Starting a new job with PID 6610
2019-05-30 20:34:59,711 [salt.minion      :1711][INFO    ][6610] Returning information for job: 20190530203459646706
2019-05-30 20:35:25,347 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.38828611s left)
2019-05-30 20:35:29,742 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203529727134
2019-05-30 20:35:29,773 [salt.minion      :1432][INFO    ][6647] Starting a new job with PID 6647
2019-05-30 20:35:29,804 [salt.minion      :1711][INFO    ][6647] Returning information for job: 20190530203529727134
2019-05-30 20:35:56,192 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.54376698s left)
2019-05-30 20:35:59,829 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203559813314
2019-05-30 20:35:59,860 [salt.minion      :1432][INFO    ][6688] Starting a new job with PID 6688
2019-05-30 20:35:59,891 [salt.minion      :1711][INFO    ][6688] Returning information for job: 20190530203559813314
2019-05-30 20:36:27,283 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.45255208s left)
2019-05-30 20:36:29,933 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203629920298
2019-05-30 20:36:29,961 [salt.minion      :1432][INFO    ][6757] Starting a new job with PID 6757
2019-05-30 20:36:29,992 [salt.minion      :1711][INFO    ][6757] Returning information for job: 20190530203629920298
2019-05-30 20:36:58,308 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.42800999s left)
2019-05-30 20:37:00,046 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203700035649
2019-05-30 20:37:00,067 [salt.minion      :1432][INFO    ][6908] Starting a new job with PID 6908
2019-05-30 20:37:00,096 [salt.minion      :1711][INFO    ][6908] Returning information for job: 20190530203700035649
2019-05-30 20:37:29,565 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.17087507s left)
2019-05-30 20:37:30,154 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203730136509
2019-05-30 20:37:30,180 [salt.minion      :1432][INFO    ][7049] Starting a new job with PID 7049
2019-05-30 20:37:30,220 [salt.minion      :1711][INFO    ][7049] Returning information for job: 20190530203730136509
2019-05-30 20:38:00,308 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203800294456
2019-05-30 20:38:00,329 [salt.minion      :1432][INFO    ][7319] Starting a new job with PID 7319
2019-05-30 20:38:00,361 [salt.minion      :1711][INFO    ][7319] Returning information for job: 20190530203800294456
2019-05-30 20:38:00,857 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.878865s left)
2019-05-30 20:38:30,419 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203830404518
2019-05-30 20:38:30,452 [salt.minion      :1432][INFO    ][7451] Starting a new job with PID 7451
2019-05-30 20:38:30,487 [salt.minion      :1711][INFO    ][7451] Returning information for job: 20190530203830404518
2019-05-30 20:38:32,675 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.06063914s left)
2019-05-30 20:39:00,596 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203900578851
2019-05-30 20:39:00,624 [salt.minion      :1432][INFO    ][7693] Starting a new job with PID 7693
2019-05-30 20:39:00,656 [salt.minion      :1711][INFO    ][7693] Returning information for job: 20190530203900578851
2019-05-30 20:39:04,735 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.00088501s left)
2019-05-30 20:39:30,739 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530203930726742
2019-05-30 20:39:30,767 [salt.minion      :1432][INFO    ][7950] Starting a new job with PID 7950
2019-05-30 20:39:30,800 [salt.minion      :1711][INFO    ][7950] Returning information for job: 20190530203930726742
2019-05-30 20:39:36,927 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.80817699s left)
2019-05-30 20:40:00,886 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204000872056
2019-05-30 20:40:00,915 [salt.minion      :1432][INFO    ][8106] Starting a new job with PID 8106
2019-05-30 20:40:00,946 [salt.minion      :1711][INFO    ][8106] Returning information for job: 20190530204000872056
2019-05-30 20:40:09,268 [salt.loaded.ext.module.maas:1023][INFO    ][6534] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1153.46790314s left)
2019-05-30 20:40:31,040 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204031026985
2019-05-30 20:40:31,068 [salt.minion      :1432][INFO    ][8267] Starting a new job with PID 8267
2019-05-30 20:40:31,100 [salt.minion      :1711][INFO    ][8267] Returning information for job: 20190530204031026985
2019-05-30 20:40:42,183 [salt.state       :300 ][INFO    ][6534] {'ret': True}
2019-05-30 20:40:42,184 [salt.state       :1951][INFO    ][6534] Completed state [maas.wait_for_machine_status] at time 20:40:42.184353 duration_in_ms=379462.534
2019-05-30 20:40:42,188 [salt.minion      :1711][INFO    ][6534] Returning information for job: 20190530203414530354
2019-05-30 20:40:42,991 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530204042979049
2019-05-30 20:40:43,018 [salt.minion      :1432][INFO    ][8317] Starting a new job with PID 8317
2019-05-30 20:40:49,242 [salt.state       :915 ][INFO    ][8317] Loading fresh modules for state activity
2019-05-30 20:40:49,311 [salt.fileclient  :1219][INFO    ][8317] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-30 20:40:49,424 [salt.state       :1780][INFO    ][8317] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:40:49.424087
2019-05-30 20:40:49,424 [salt.state       :1813][INFO    ][8317] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 20:40:49,426 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8317] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 20:40:51,348 [salt.state       :300 ][INFO    ][8317] {'pid': 8388, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 20:40:51,350 [salt.state       :1951][INFO    ][8317] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:40:51.349522 duration_in_ms=1925.434
2019-05-30 20:40:51,354 [salt.state       :1780][INFO    ][8317] Running state [maas_machines_storage_cmp002_lvm] at time 20:40:51.354402
2019-05-30 20:40:51,354 [salt.state       :1813][INFO    ][8317] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-30 20:40:52,504 [salt.loaded.ext.module.maasng:610 ][INFO    ][8317] 7qqfbn
2019-05-30 20:40:52,504 [salt.loaded.ext.module.maasng:626 ][INFO    ][8317] sda
2019-05-30 20:40:53,081 [salt.loaded.ext.module.maasng:361 ][INFO    ][8317] 7qqfbn
2019-05-30 20:40:53,171 [salt.loaded.ext.module.maasng:367 ][INFO    ][8317] [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'name': u'sda', u'system_id': u'7qqfbn', 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/7qqfbn/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'28807a50-72c3-4fad-8518-ac129ab7ea0a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7qqfbn', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'dc938905-7561-4a2a-9d02-4627c28aa38c', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/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'uuid': u'0693c683-5aca-4a42-832f-febcb8220262', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'name': u'vgroot-lvroot', u'system_id': u'7qqfbn', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'6832d512-9b52-4434-8d16-f67bf5496ece', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/blockdevices/3/', u'type': u'virtual', u'id': 3, u'serial': None, u'partitions': []}]
2019-05-30 20:40:53,172 [salt.loaded.ext.module.maasng:632 ][INFO    ][8317] vgroot
2019-05-30 20:40:53,173 [salt.loaded.ext.module.maasng:635 ][INFO    ][8317] lvroot
2019-05-30 20:40:53,173 [salt.loaded.ext.module.maasng:639 ][INFO    ][8317] 107374182400
2019-05-30 20:40:53,771 [salt.loaded.ext.module.maasng:645 ][INFO    ][8317] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', 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'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'name': u'sda', u'system_id': u'7qqfbn', u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/blockdevices/1/', 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'size': 800109715456, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'ff77d5d3-fd5d-44b3-9522-20832c43b8fa', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7qqfbn', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'5fecee74-2391-41d1-a7a7-fc4f367e8088', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/7qqfbn/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'7qqfbn', 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'physicalblockdevice_set': [{u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'name': u'sda', u'system_id': u'7qqfbn', u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/blockdevices/1/', 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'size': 800109715456, u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'ff77d5d3-fd5d-44b3-9522-20832c43b8fa', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7qqfbn', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'5fecee74-2391-41d1-a7a7-fc4f367e8088', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'name': u'sda', u'system_id': u'7qqfbn', 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/7qqfbn/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'ff77d5d3-fd5d-44b3-9522-20832c43b8fa', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7qqfbn', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'5fecee74-2391-41d1-a7a7-fc4f367e8088', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'device_id': 1}]}, {u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'uuid': u'90b6c92d-cb63-466e-a46d-42d8cedbf456', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'name': u'vgroot-lvroot', u'system_id': u'7qqfbn', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'c522cb60-8a28-4dfd-adb2-f67dba45e519', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/blockdevices/11/', u'type': u'virtual', u'id': 11, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7qqfbn', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 23}], 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'7qqfbn', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/interfaces/4/'}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 23}], 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'7qqfbn', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/interfaces/4/'}, {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'system_id': u'7qqfbn', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/interfaces/11/'}, {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'system_id': u'7qqfbn', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 24}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'7qqfbn', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/interfaces/12/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'7qqfbn', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/interfaces/14/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'mode': u'link_up', u'id': 26}], 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'7qqfbn', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/interfaces/15/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'virtualblockdevice_set': [{u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'90b6c92d-cb63-466e-a46d-42d8cedbf456', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'name': u'vgroot-lvroot', u'system_id': u'7qqfbn', u'resource_uri': u'/MAAS/api/2.0/nodes/7qqfbn/blockdevices/11/', u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'c522cb60-8a28-4dfd-adb2-f67dba45e519', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 11, u'serial': None, u'partitions': []}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-30 20:40:53,775 [salt.state       :300 ][INFO    ][8317] {'new': {'storage_layout': 'lvm'}}
2019-05-30 20:40:53,776 [salt.state       :1951][INFO    ][8317] Completed state [maas_machines_storage_cmp002_lvm] at time 20:40:53.775964 duration_in_ms=2421.561
2019-05-30 20:40:53,776 [salt.state       :1780][INFO    ][8317] Running state [maas_machines_storage_cmp001_lvm] at time 20:40:53.776386
2019-05-30 20:40:53,776 [salt.state       :1813][INFO    ][8317] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-30 20:40:54,929 [salt.loaded.ext.module.maasng:610 ][INFO    ][8317] dpsxd4
2019-05-30 20:40:54,930 [salt.loaded.ext.module.maasng:626 ][INFO    ][8317] sda
2019-05-30 20:40:55,492 [salt.loaded.ext.module.maasng:361 ][INFO    ][8317] dpsxd4
2019-05-30 20:40:55,583 [salt.loaded.ext.module.maasng:367 ][INFO    ][8317] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'a4beba56-3612-475f-a993-de7f1e76c77f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dpsxd4', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'9bce538e-41d2-4f79-b5fb-1926afab6208', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'dpsxd4', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'uuid': u'92a95d58-0d50-4996-9c62-b8164b76f421', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'55cd7557-cfa4-49c4-9c0e-7dbcbb23a4cc', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dpsxd4', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/blockdevices/4/', u'used_size': 800097042432, u'id': 4, u'size': 800097042432}]
2019-05-30 20:40:55,583 [salt.loaded.ext.module.maasng:632 ][INFO    ][8317] vgroot
2019-05-30 20:40:55,584 [salt.loaded.ext.module.maasng:635 ][INFO    ][8317] lvroot
2019-05-30 20:40:55,584 [salt.loaded.ext.module.maasng:639 ][INFO    ][8317] 107374182400
2019-05-30 20:40:56,191 [salt.loaded.ext.module.maasng:645 ][INFO    ][8317] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', 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'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'name': u'sda', u'system_id': u'dpsxd4', u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'5d7b0b8c-3168-427d-9b9b-13ba4913e958', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dpsxd4', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'e93e4109-4efd-467c-b272-86043162760d', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/dpsxd4/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'dpsxd4', 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'physicalblockdevice_set': [{u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'name': u'sda', u'system_id': u'dpsxd4', u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'5d7b0b8c-3168-427d-9b9b-13ba4913e958', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dpsxd4', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'e93e4109-4efd-467c-b272-86043162760d', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'name': u'sda', u'system_id': u'dpsxd4', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'5d7b0b8c-3168-427d-9b9b-13ba4913e958', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dpsxd4', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'e93e4109-4efd-467c-b272-86043162760d', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}, {u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'uuid': u'a3c68aea-bb0e-4d90-8de5-d72c37c55be3', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'name': u'vgroot-lvroot', u'system_id': u'dpsxd4', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'6277dd88-a241-4f55-861e-b445f15642e8', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'dpsxd4', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 29}], 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'dpsxd4', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/interfaces/5/'}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 29}], 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'dpsxd4', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/interfaces/5/'}, {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'system_id': u'dpsxd4', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/interfaces/18/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 30}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'dpsxd4', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/interfaces/17/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 31}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'dpsxd4', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/interfaces/19/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'mode': u'link_up', u'id': 32}], 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': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}, 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': 5, u'resource_uri': u'/MAAS/api/2.0/subnets/5/'}, u'ip_address': u'192.168.11.44'}], u'system_id': u'dpsxd4', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/interfaces/20/'}, {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'system_id': u'dpsxd4', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/interfaces/16/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'virtualblockdevice_set': [{u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'a3c68aea-bb0e-4d90-8de5-d72c37c55be3', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'name': u'vgroot-lvroot', u'system_id': u'dpsxd4', u'resource_uri': u'/MAAS/api/2.0/nodes/dpsxd4/blockdevices/12/', u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'6277dd88-a241-4f55-861e-b445f15642e8', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-30 20:40:56,195 [salt.state       :300 ][INFO    ][8317] {'new': {'storage_layout': 'lvm'}}
2019-05-30 20:40:56,195 [salt.state       :1951][INFO    ][8317] Completed state [maas_machines_storage_cmp001_lvm] at time 20:40:56.195605 duration_in_ms=2419.217
2019-05-30 20:40:56,203 [salt.minion      :1711][INFO    ][8317] Returning information for job: 20190530204042979049
2019-05-30 20:40:56,949 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530204056939053
2019-05-30 20:40:56,980 [salt.minion      :1432][INFO    ][8411] Starting a new job with PID 8411
2019-05-30 20:40:58,000 [salt.state       :915 ][INFO    ][8411] Loading fresh modules for state activity
2019-05-30 20:40:58,074 [salt.fileclient  :1219][INFO    ][8411] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-30 20:40:58,131 [salt.state       :1780][INFO    ][8411] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:40:58.131297
2019-05-30 20:40:58,131 [salt.state       :1813][INFO    ][8411] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 20:40:58,134 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8411] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 20:41:00,032 [salt.state       :300 ][INFO    ][8411] {'pid': 8418, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 20:41:00,033 [salt.state       :1951][INFO    ][8411] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:41:00.033430 duration_in_ms=1902.133
2019-05-30 20:41:00,037 [salt.state       :1780][INFO    ][8411] Running state [maas.deploy_machines] at time 20:41:00.037034
2019-05-30 20:41:00,039 [salt.state       :1813][INFO    ][8411] Executing state module.run for [maas.deploy_machines]
2019-05-30 20:41:00,040 [salt.utils.decorators:613 ][WARNING ][8411] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 20:41:00,634 [salt.loaded.ext.module.maas:684 ][INFO    ][8411] deploymachines hwe_kernel=hwe-16.04 system_id=7qqfbn distro_series=xenial
2019-05-30 20:41:03,128 [salt.loaded.ext.module.maas:684 ][INFO    ][8411] deploymachines hwe_kernel=hwe-16.04 system_id=dpsxd4 distro_series=xenial
2019-05-30 20:41:05,743 [salt.loaded.ext.module.maas:684 ][INFO    ][8411] deploymachines hwe_kernel=hwe-16.04 system_id=awsqdy distro_series=xenial
2019-05-30 20:41:08,434 [salt.loaded.ext.module.maas:684 ][INFO    ][8411] deploymachines hwe_kernel=hwe-16.04 system_id=h3rcfs distro_series=xenial
2019-05-30 20:41:11,000 [salt.loaded.ext.module.maas:684 ][INFO    ][8411] deploymachines hwe_kernel=hwe-16.04 system_id=pkepyc distro_series=xenial
2019-05-30 20:41:12,068 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204112053909
2019-05-30 20:41:12,093 [salt.minion      :1432][INFO    ][8669] Starting a new job with PID 8669
2019-05-30 20:41:12,126 [salt.minion      :1711][INFO    ][8669] Returning information for job: 20190530204112053909
2019-05-30 20:41:13,576 [salt.state       :300 ][INFO    ][8411] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-30 20:41:13,577 [salt.state       :1951][INFO    ][8411] Completed state [maas.deploy_machines] at time 20:41:13.576922 duration_in_ms=13539.888
2019-05-30 20:41:13,579 [salt.minion      :1711][INFO    ][8411] Returning information for job: 20190530204056939053
2019-05-30 20:41:14,316 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530204114303055
2019-05-30 20:41:14,343 [salt.minion      :1432][INFO    ][8696] Starting a new job with PID 8696
2019-05-30 20:41:20,464 [salt.state       :915 ][INFO    ][8696] Loading fresh modules for state activity
2019-05-30 20:41:20,517 [salt.fileclient  :1219][INFO    ][8696] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-30 20:41:20,561 [salt.state       :1780][INFO    ][8696] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:41:20.561552
2019-05-30 20:41:20,562 [salt.state       :1813][INFO    ][8696] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 20:41:20,563 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8696] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 20:41:22,440 [salt.state       :300 ][INFO    ][8696] {'pid': 8726, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 20:41:22,441 [salt.state       :1951][INFO    ][8696] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:41:22.441472 duration_in_ms=1879.921
2019-05-30 20:41:22,445 [salt.state       :1780][INFO    ][8696] Running state [maas.wait_for_machine_status] at time 20:41:22.445327
2019-05-30 20:41:22,445 [salt.state       :1813][INFO    ][8696] Executing state module.run for [maas.wait_for_machine_status]
2019-05-30 20:41:22,446 [salt.utils.decorators:613 ][WARNING ][8696] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 20:41:25,439 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.02638912s left)
2019-05-30 20:41:29,429 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204129415284
2019-05-30 20:41:29,456 [salt.minion      :1432][INFO    ][8752] Starting a new job with PID 8752
2019-05-30 20:41:29,489 [salt.minion      :1711][INFO    ][8752] Returning information for job: 20190530204129415284
2019-05-30 20:41:58,363 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.10226107s left)
2019-05-30 20:41:59,529 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204159511383
2019-05-30 20:41:59,560 [salt.minion      :1432][INFO    ][8807] Starting a new job with PID 8807
2019-05-30 20:41:59,590 [salt.minion      :1711][INFO    ][8807] Returning information for job: 20190530204159511383
2019-05-30 20:42:29,608 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204229597197
2019-05-30 20:42:29,628 [salt.minion      :1432][INFO    ][8837] Starting a new job with PID 8837
2019-05-30 20:42:29,660 [salt.minion      :1711][INFO    ][8837] Returning information for job: 20190530204229597197
2019-05-30 20:42:31,258 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.20697212s left)
2019-05-30 20:42:59,690 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204259675538
2019-05-30 20:42:59,727 [salt.minion      :1432][INFO    ][8874] Starting a new job with PID 8874
2019-05-30 20:42:59,765 [salt.minion      :1711][INFO    ][8874] Returning information for job: 20190530204259675538
2019-05-30 20:43:04,127 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.33829808s left)
2019-05-30 20:43:29,800 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204329785176
2019-05-30 20:43:29,827 [salt.minion      :1432][INFO    ][8906] Starting a new job with PID 8906
2019-05-30 20:43:29,873 [salt.minion      :1711][INFO    ][8906] Returning information for job: 20190530204329785176
2019-05-30 20:43:36,625 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.84023499s left)
2019-05-30 20:43:59,886 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204359870644
2019-05-30 20:43:59,916 [salt.minion      :1432][INFO    ][8966] Starting a new job with PID 8966
2019-05-30 20:43:59,950 [salt.minion      :1711][INFO    ][8966] Returning information for job: 20190530204359870644
2019-05-30 20:44:09,713 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.75174999s left)
2019-05-30 20:44:29,972 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204429959844
2019-05-30 20:44:30,005 [salt.minion      :1432][INFO    ][9059] Starting a new job with PID 9059
2019-05-30 20:44:30,045 [salt.minion      :1711][INFO    ][9059] Returning information for job: 20190530204429959844
2019-05-30 20:44:42,781 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.68412805s left)
2019-05-30 20:45:00,118 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204500102569
2019-05-30 20:45:00,147 [salt.minion      :1432][INFO    ][9266] Starting a new job with PID 9266
2019-05-30 20:45:00,184 [salt.minion      :1711][INFO    ][9266] Returning information for job: 20190530204500102569
2019-05-30 20:45:15,756 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.70907593s left)
2019-05-30 20:45:30,220 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204530212050
2019-05-30 20:45:30,240 [salt.minion      :1432][INFO    ][9363] Starting a new job with PID 9363
2019-05-30 20:45:30,271 [salt.minion      :1711][INFO    ][9363] Returning information for job: 20190530204530212050
2019-05-30 20:45:48,562 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.90273094s left)
2019-05-30 20:46:00,355 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204600341916
2019-05-30 20:46:00,384 [salt.minion      :1432][INFO    ][9558] Starting a new job with PID 9558
2019-05-30 20:46:00,420 [salt.minion      :1711][INFO    ][9558] Returning information for job: 20190530204600341916
2019-05-30 20:46:21,825 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.63999796s left)
2019-05-30 20:46:30,504 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204630486246
2019-05-30 20:46:30,533 [salt.minion      :1432][INFO    ][9673] Starting a new job with PID 9673
2019-05-30 20:46:30,576 [salt.minion      :1711][INFO    ][9673] Returning information for job: 20190530204630486246
2019-05-30 20:46:54,818 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.64784813s left)
2019-05-30 20:47:00,731 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204700717974
2019-05-30 20:47:00,766 [salt.minion      :1432][INFO    ][10103] Starting a new job with PID 10103
2019-05-30 20:47:00,803 [salt.minion      :1711][INFO    ][10103] Returning information for job: 20190530204700717974
2019-05-30 20:47:27,943 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.52230096s left)
2019-05-30 20:47:30,888 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204730874216
2019-05-30 20:47:30,921 [salt.minion      :1432][INFO    ][10210] Starting a new job with PID 10210
2019-05-30 20:47:30,958 [salt.minion      :1711][INFO    ][10210] Returning information for job: 20190530204730874216
2019-05-30 20:48:00,899 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1851.56592011s left)
2019-05-30 20:48:01,065 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204801052639
2019-05-30 20:48:01,087 [salt.minion      :1432][INFO    ][10444] Starting a new job with PID 10444
2019-05-30 20:48:01,120 [salt.minion      :1711][INFO    ][10444] Returning information for job: 20190530204801052639
2019-05-30 20:48:31,207 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204831191322
2019-05-30 20:48:31,232 [salt.minion      :1432][INFO    ][10506] Starting a new job with PID 10506
2019-05-30 20:48:31,265 [salt.minion      :1711][INFO    ][10506] Returning information for job: 20190530204831191322
2019-05-30 20:48:33,958 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1818.50705194s left)
2019-05-30 20:49:01,407 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204901394640
2019-05-30 20:49:01,434 [salt.minion      :1432][INFO    ][10730] Starting a new job with PID 10730
2019-05-30 20:49:01,465 [salt.minion      :1711][INFO    ][10730] Returning information for job: 20190530204901394640
2019-05-30 20:49:06,788 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1785.67656803s left)
2019-05-30 20:49:31,567 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530204931556282
2019-05-30 20:49:31,595 [salt.minion      :1432][INFO    ][10876] Starting a new job with PID 10876
2019-05-30 20:49:31,627 [salt.minion      :1711][INFO    ][10876] Returning information for job: 20190530204931556282
2019-05-30 20:49:39,713 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1752.751827s left)
2019-05-30 20:50:01,747 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205001738232
2019-05-30 20:50:01,780 [salt.minion      :1432][INFO    ][11044] Starting a new job with PID 11044
2019-05-30 20:50:01,811 [salt.minion      :1711][INFO    ][11044] Returning information for job: 20190530205001738232
2019-05-30 20:50:12,451 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1720.01420808s left)
2019-05-30 20:50:31,928 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205031913206
2019-05-30 20:50:31,954 [salt.minion      :1432][INFO    ][11149] Starting a new job with PID 11149
2019-05-30 20:50:31,991 [salt.minion      :1711][INFO    ][11149] Returning information for job: 20190530205031913206
2019-05-30 20:50:45,559 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1686.90625596s left)
2019-05-30 20:51:02,098 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205102083057
2019-05-30 20:51:02,127 [salt.minion      :1432][INFO    ][11433] Starting a new job with PID 11433
2019-05-30 20:51:02,159 [salt.minion      :1711][INFO    ][11433] Returning information for job: 20190530205102083057
2019-05-30 20:51:18,737 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1653.72894692s left)
2019-05-30 20:51:32,227 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205132211648
2019-05-30 20:51:32,255 [salt.minion      :1432][INFO    ][11524] Starting a new job with PID 11524
2019-05-30 20:51:32,284 [salt.minion      :1711][INFO    ][11524] Returning information for job: 20190530205132211648
2019-05-30 20:51:52,163 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1620.30208707s left)
2019-05-30 20:52:02,426 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205202411437
2019-05-30 20:52:02,456 [salt.minion      :1432][INFO    ][11677] Starting a new job with PID 11677
2019-05-30 20:52:02,490 [salt.minion      :1711][INFO    ][11677] Returning information for job: 20190530205202411437
2019-05-30 20:52:25,017 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1587.44818711s left)
2019-05-30 20:52:32,610 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205232595268
2019-05-30 20:52:32,637 [salt.minion      :1432][INFO    ][11714] Starting a new job with PID 11714
2019-05-30 20:52:32,670 [salt.minion      :1711][INFO    ][11714] Returning information for job: 20190530205232595268
2019-05-30 20:52:58,133 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1554.33233714s left)
2019-05-30 20:53:02,803 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205302791250
2019-05-30 20:53:02,832 [salt.minion      :1432][INFO    ][11754] Starting a new job with PID 11754
2019-05-30 20:53:02,864 [salt.minion      :1711][INFO    ][11754] Returning information for job: 20190530205302791250
2019-05-30 20:53:30,876 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1521.58941793s left)
2019-05-30 20:53:32,993 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205332979158
2019-05-30 20:53:33,017 [salt.minion      :1432][INFO    ][11810] Starting a new job with PID 11810
2019-05-30 20:53:33,048 [salt.minion      :1711][INFO    ][11810] Returning information for job: 20190530205332979158
2019-05-30 20:54:03,133 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205403122893
2019-05-30 20:54:03,154 [salt.minion      :1432][INFO    ][11902] Starting a new job with PID 11902
2019-05-30 20:54:03,190 [salt.minion      :1711][INFO    ][11902] Returning information for job: 20190530205403122893
2019-05-30 20:54:03,834 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1488.63173795s left)
2019-05-30 20:54:33,148 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205433140157
2019-05-30 20:54:33,173 [salt.minion      :1432][INFO    ][12032] Starting a new job with PID 12032
2019-05-30 20:54:33,203 [salt.minion      :1711][INFO    ][12032] Returning information for job: 20190530205433140157
2019-05-30 20:54:36,801 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1455.66395497s left)
2019-05-30 20:55:03,191 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205503174422
2019-05-30 20:55:03,221 [salt.minion      :1432][INFO    ][12211] Starting a new job with PID 12211
2019-05-30 20:55:03,255 [salt.minion      :1711][INFO    ][12211] Returning information for job: 20190530205503174422
2019-05-30 20:55:09,807 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1422.6577971s left)
2019-05-30 20:55:33,243 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205533225591
2019-05-30 20:55:33,271 [salt.minion      :1432][INFO    ][12247] Starting a new job with PID 12247
2019-05-30 20:55:33,307 [salt.minion      :1711][INFO    ][12247] Returning information for job: 20190530205533225591
2019-05-30 20:55:42,744 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1389.72149897s left)
2019-05-30 20:56:03,292 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205603277436
2019-05-30 20:56:03,318 [salt.minion      :1432][INFO    ][12291] Starting a new job with PID 12291
2019-05-30 20:56:03,349 [salt.minion      :1711][INFO    ][12291] Returning information for job: 20190530205603277436
2019-05-30 20:56:15,584 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1356.88136196s left)
2019-05-30 20:56:33,353 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205633336642
2019-05-30 20:56:33,387 [salt.minion      :1432][INFO    ][12335] Starting a new job with PID 12335
2019-05-30 20:56:33,423 [salt.minion      :1711][INFO    ][12335] Returning information for job: 20190530205633336642
2019-05-30 20:56:48,305 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1324.16008711s left)
2019-05-30 20:57:03,403 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205703388853
2019-05-30 20:57:03,433 [salt.minion      :1432][INFO    ][12381] Starting a new job with PID 12381
2019-05-30 20:57:03,465 [salt.minion      :1711][INFO    ][12381] Returning information for job: 20190530205703388853
2019-05-30 20:57:21,331 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1291.13382506s left)
2019-05-30 20:57:33,488 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205733472974
2019-05-30 20:57:33,520 [salt.minion      :1432][INFO    ][12416] Starting a new job with PID 12416
2019-05-30 20:57:33,552 [salt.minion      :1711][INFO    ][12416] Returning information for job: 20190530205733472974
2019-05-30 20:57:54,045 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1258.41971207s left)
2019-05-30 20:58:03,584 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205803564003
2019-05-30 20:58:03,612 [salt.minion      :1432][INFO    ][12457] Starting a new job with PID 12457
2019-05-30 20:58:03,643 [salt.minion      :1711][INFO    ][12457] Returning information for job: 20190530205803564003
2019-05-30 20:58:26,932 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1225.53282213s left)
2019-05-30 20:58:33,687 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205833672778
2019-05-30 20:58:33,714 [salt.minion      :1432][INFO    ][12499] Starting a new job with PID 12499
2019-05-30 20:58:33,747 [salt.minion      :1711][INFO    ][12499] Returning information for job: 20190530205833672778
2019-05-30 20:58:59,796 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1192.66931796s left)
2019-05-30 20:59:03,797 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205903783768
2019-05-30 20:59:03,825 [salt.minion      :1432][INFO    ][12543] Starting a new job with PID 12543
2019-05-30 20:59:03,856 [salt.minion      :1711][INFO    ][12543] Returning information for job: 20190530205903783768
2019-05-30 20:59:32,544 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1159.92066693s left)
2019-05-30 20:59:33,915 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530205933901243
2019-05-30 20:59:33,941 [salt.minion      :1432][INFO    ][12577] Starting a new job with PID 12577
2019-05-30 20:59:33,974 [salt.minion      :1711][INFO    ][12577] Returning information for job: 20190530205933901243
2019-05-30 21:00:04,065 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210004057561
2019-05-30 21:00:04,080 [salt.minion      :1432][INFO    ][12619] Starting a new job with PID 12619
2019-05-30 21:00:04,113 [salt.minion      :1711][INFO    ][12619] Returning information for job: 20190530210004057561
2019-05-30 21:00:05,415 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1127.04955697s left)
2019-05-30 21:00:34,219 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210034203714
2019-05-30 21:00:34,249 [salt.minion      :1432][INFO    ][12653] Starting a new job with PID 12653
2019-05-30 21:00:34,277 [salt.minion      :1711][INFO    ][12653] Returning information for job: 20190530210034203714
2019-05-30 21:00:38,254 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1094.21070814s left)
2019-05-30 21:01:04,401 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210104389369
2019-05-30 21:01:04,426 [salt.minion      :1432][INFO    ][12714] Starting a new job with PID 12714
2019-05-30 21:01:04,463 [salt.minion      :1711][INFO    ][12714] Returning information for job: 20190530210104389369
2019-05-30 21:01:10,930 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1061.53495407s left)
2019-05-30 21:01:34,571 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210134562467
2019-05-30 21:01:34,603 [salt.minion      :1432][INFO    ][12750] Starting a new job with PID 12750
2019-05-30 21:01:34,632 [salt.minion      :1711][INFO    ][12750] Returning information for job: 20190530210134562467
2019-05-30 21:01:43,625 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1028.84054708s left)
2019-05-30 21:02:04,775 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210204760064
2019-05-30 21:02:04,803 [salt.minion      :1432][INFO    ][12796] Starting a new job with PID 12796
2019-05-30 21:02:04,834 [salt.minion      :1711][INFO    ][12796] Returning information for job: 20190530210204760064
2019-05-30 21:02:16,346 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (996.118968964s left)
2019-05-30 21:02:34,989 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210234978366
2019-05-30 21:02:35,021 [salt.minion      :1432][INFO    ][12848] Starting a new job with PID 12848
2019-05-30 21:02:35,056 [salt.minion      :1711][INFO    ][12848] Returning information for job: 20190530210234978366
2019-05-30 21:02:49,786 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (962.678950071s left)
2019-05-30 21:03:05,015 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210305004239
2019-05-30 21:03:05,044 [salt.minion      :1432][INFO    ][12881] Starting a new job with PID 12881
2019-05-30 21:03:05,078 [salt.minion      :1711][INFO    ][12881] Returning information for job: 20190530210305004239
2019-05-30 21:03:22,343 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (930.122035027s left)
2019-05-30 21:03:35,045 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210335032524
2019-05-30 21:03:35,073 [salt.minion      :1432][INFO    ][12925] Starting a new job with PID 12925
2019-05-30 21:03:35,113 [salt.minion      :1711][INFO    ][12925] Returning information for job: 20190530210335032524
2019-05-30 21:03:55,151 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (897.314192057s left)
2019-05-30 21:04:05,130 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210405115178
2019-05-30 21:04:05,158 [salt.minion      :1432][INFO    ][12956] Starting a new job with PID 12956
2019-05-30 21:04:05,194 [salt.minion      :1711][INFO    ][12956] Returning information for job: 20190530210405115178
2019-05-30 21:04:27,680 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (864.784567118s left)
2019-05-30 21:04:35,355 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210435343167
2019-05-30 21:04:35,377 [salt.minion      :1432][INFO    ][12999] Starting a new job with PID 12999
2019-05-30 21:04:35,412 [salt.minion      :1711][INFO    ][12999] Returning information for job: 20190530210435343167
2019-05-30 21:05:00,451 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (832.014258146s left)
2019-05-30 21:05:05,440 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210505423687
2019-05-30 21:05:05,465 [salt.minion      :1432][INFO    ][13034] Starting a new job with PID 13034
2019-05-30 21:05:05,497 [salt.minion      :1711][INFO    ][13034] Returning information for job: 20190530210505423687
2019-05-30 21:05:33,178 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (799.286697149s left)
2019-05-30 21:05:35,515 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210535498815
2019-05-30 21:05:35,539 [salt.minion      :1432][INFO    ][13075] Starting a new job with PID 13075
2019-05-30 21:05:35,576 [salt.minion      :1711][INFO    ][13075] Returning information for job: 20190530210535498815
2019-05-30 21:06:05,638 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210605627349
2019-05-30 21:06:05,660 [salt.minion      :1432][INFO    ][13108] Starting a new job with PID 13108
2019-05-30 21:06:05,695 [salt.minion      :1711][INFO    ][13108] Returning information for job: 20190530210605627349
2019-05-30 21:06:06,119 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (766.345890999s left)
2019-05-30 21:06:35,783 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210635771748
2019-05-30 21:06:35,809 [salt.minion      :1432][INFO    ][13148] Starting a new job with PID 13148
2019-05-30 21:06:35,842 [salt.minion      :1711][INFO    ][13148] Returning information for job: 20190530210635771748
2019-05-30 21:06:38,884 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (733.580760002s left)
2019-05-30 21:07:05,899 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210705888697
2019-05-30 21:07:05,925 [salt.minion      :1432][INFO    ][13191] Starting a new job with PID 13191
2019-05-30 21:07:05,961 [salt.minion      :1711][INFO    ][13191] Returning information for job: 20190530210705888697
2019-05-30 21:07:11,661 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (700.804412127s left)
2019-05-30 21:07:36,083 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210736070597
2019-05-30 21:07:36,108 [salt.minion      :1432][INFO    ][13235] Starting a new job with PID 13235
2019-05-30 21:07:36,151 [salt.minion      :1711][INFO    ][13235] Returning information for job: 20190530210736070597
2019-05-30 21:07:44,500 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (667.965099096s left)
2019-05-30 21:08:06,283 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210806270325
2019-05-30 21:08:06,311 [salt.minion      :1432][INFO    ][13268] Starting a new job with PID 13268
2019-05-30 21:08:06,345 [salt.minion      :1711][INFO    ][13268] Returning information for job: 20190530210806270325
2019-05-30 21:08:17,477 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (634.988418102s left)
2019-05-30 21:08:36,511 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210836498269
2019-05-30 21:08:36,537 [salt.minion      :1432][INFO    ][13312] Starting a new job with PID 13312
2019-05-30 21:08:36,571 [salt.minion      :1711][INFO    ][13312] Returning information for job: 20190530210836498269
2019-05-30 21:08:50,192 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (602.273152113s left)
2019-05-30 21:09:06,728 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210906714593
2019-05-30 21:09:06,752 [salt.minion      :1432][INFO    ][13346] Starting a new job with PID 13346
2019-05-30 21:09:06,784 [salt.minion      :1711][INFO    ][13346] Returning information for job: 20190530210906714593
2019-05-30 21:09:22,907 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (569.558125973s left)
2019-05-30 21:09:36,947 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530210936939129
2019-05-30 21:09:36,975 [salt.minion      :1432][INFO    ][13391] Starting a new job with PID 13391
2019-05-30 21:09:37,007 [salt.minion      :1711][INFO    ][13391] Returning information for job: 20190530210936939129
2019-05-30 21:09:55,678 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (536.787014008s left)
2019-05-30 21:10:06,980 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211006965116
2019-05-30 21:10:07,008 [salt.minion      :1432][INFO    ][13424] Starting a new job with PID 13424
2019-05-30 21:10:07,045 [salt.minion      :1711][INFO    ][13424] Returning information for job: 20190530211006965116
2019-05-30 21:10:28,524 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (503.94096899s left)
2019-05-30 21:10:37,079 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211037064672
2019-05-30 21:10:37,109 [salt.minion      :1432][INFO    ][13598] Starting a new job with PID 13598
2019-05-30 21:10:37,147 [salt.minion      :1711][INFO    ][13598] Returning information for job: 20190530211037064672
2019-05-30 21:10:42,612 [salt.utils.schedule:1377][INFO    ][2732] Running scheduled job: __mine_interval
2019-05-30 21:11:01,108 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (471.356812s left)
2019-05-30 21:11:07,194 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211107181309
2019-05-30 21:11:07,222 [salt.minion      :1432][INFO    ][13659] Starting a new job with PID 13659
2019-05-30 21:11:07,254 [salt.minion      :1711][INFO    ][13659] Returning information for job: 20190530211107181309
2019-05-30 21:11:33,813 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (438.652434111s left)
2019-05-30 21:11:37,315 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211137300726
2019-05-30 21:11:37,343 [salt.minion      :1432][INFO    ][13706] Starting a new job with PID 13706
2019-05-30 21:11:37,373 [salt.minion      :1711][INFO    ][13706] Returning information for job: 20190530211137300726
2019-05-30 21:12:06,813 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (405.652418137s left)
2019-05-30 21:12:07,460 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211207449127
2019-05-30 21:12:07,482 [salt.minion      :1432][INFO    ][13739] Starting a new job with PID 13739
2019-05-30 21:12:07,511 [salt.minion      :1711][INFO    ][13739] Returning information for job: 20190530211207449127
2019-05-30 21:12:37,608 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211237596085
2019-05-30 21:12:37,637 [salt.minion      :1432][INFO    ][13930] Starting a new job with PID 13930
2019-05-30 21:12:37,669 [salt.minion      :1711][INFO    ][13930] Returning information for job: 20190530211237596085
2019-05-30 21:12:39,696 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (372.768767118s left)
2019-05-30 21:13:07,810 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211307793492
2019-05-30 21:13:07,843 [salt.minion      :1432][INFO    ][13959] Starting a new job with PID 13959
2019-05-30 21:13:07,875 [salt.minion      :1711][INFO    ][13959] Returning information for job: 20190530211307793492
2019-05-30 21:13:12,352 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (340.113513947s left)
2019-05-30 21:13:38,028 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211338014784
2019-05-30 21:13:38,057 [salt.minion      :1432][INFO    ][14001] Starting a new job with PID 14001
2019-05-30 21:13:38,088 [salt.minion      :1711][INFO    ][14001] Returning information for job: 20190530211338014784
2019-05-30 21:13:45,060 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (307.404690981s left)
2019-05-30 21:14:08,068 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211408053344
2019-05-30 21:14:08,092 [salt.minion      :1432][INFO    ][14036] Starting a new job with PID 14036
2019-05-30 21:14:08,123 [salt.minion      :1711][INFO    ][14036] Returning information for job: 20190530211408053344
2019-05-30 21:14:17,976 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (274.488986015s left)
2019-05-30 21:14:38,286 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211438275453
2019-05-30 21:14:38,314 [salt.minion      :1432][INFO    ][14077] Starting a new job with PID 14077
2019-05-30 21:14:38,344 [salt.minion      :1711][INFO    ][14077] Returning information for job: 20190530211438275453
2019-05-30 21:14:50,949 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (241.515552044s left)
2019-05-30 21:15:08,369 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211508353266
2019-05-30 21:15:08,399 [salt.minion      :1432][INFO    ][14112] Starting a new job with PID 14112
2019-05-30 21:15:08,428 [salt.minion      :1711][INFO    ][14112] Returning information for job: 20190530211508353266
2019-05-30 21:15:23,744 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (208.720870972s left)
2019-05-30 21:15:38,410 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211538402714
2019-05-30 21:15:38,436 [salt.minion      :1432][INFO    ][14161] Starting a new job with PID 14161
2019-05-30 21:15:38,469 [salt.minion      :1711][INFO    ][14161] Returning information for job: 20190530211538402714
2019-05-30 21:15:56,635 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (175.830461979s left)
2019-05-30 21:16:08,539 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211608528751
2019-05-30 21:16:08,567 [salt.minion      :1432][INFO    ][14194] Starting a new job with PID 14194
2019-05-30 21:16:08,601 [salt.minion      :1711][INFO    ][14194] Returning information for job: 20190530211608528751
2019-05-30 21:16:29,339 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (143.125956059s left)
2019-05-30 21:16:38,622 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211638605445
2019-05-30 21:16:38,648 [salt.minion      :1432][INFO    ][14241] Starting a new job with PID 14241
2019-05-30 21:16:38,683 [salt.minion      :1711][INFO    ][14241] Returning information for job: 20190530211638605445
2019-05-30 21:17:02,118 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (110.346759081s left)
2019-05-30 21:17:08,780 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211708763556
2019-05-30 21:17:08,807 [salt.minion      :1432][INFO    ][14283] Starting a new job with PID 14283
2019-05-30 21:17:08,844 [salt.minion      :1711][INFO    ][14283] Returning information for job: 20190530211708763556
2019-05-30 21:17:35,051 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (77.4143640995s left)
2019-05-30 21:17:38,957 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211738942820
2019-05-30 21:17:38,980 [salt.minion      :1432][INFO    ][14327] Starting a new job with PID 14327
2019-05-30 21:17:39,010 [salt.minion      :1711][INFO    ][14327] Returning information for job: 20190530211738942820
2019-05-30 21:18:07,919 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (44.5464410782s left)
2019-05-30 21:18:09,164 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211809148417
2019-05-30 21:18:09,192 [salt.minion      :1432][INFO    ][14360] Starting a new job with PID 14360
2019-05-30 21:18:09,230 [salt.minion      :1711][INFO    ][14360] Returning information for job: 20190530211809148417
2019-05-30 21:18:39,379 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211839367186
2019-05-30 21:18:39,400 [salt.minion      :1432][INFO    ][14401] Starting a new job with PID 14401
2019-05-30 21:18:39,432 [salt.minion      :1711][INFO    ][14401] Returning information for job: 20190530211839367186
2019-05-30 21:18:40,700 [salt.loaded.ext.module.maas:1023][INFO    ][8696] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (11.7646160126s left)
2019-05-30 21:19:09,558 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211909546547
2019-05-30 21:19:09,587 [salt.minion      :1432][INFO    ][14434] Starting a new job with PID 14434
2019-05-30 21:19:09,615 [salt.minion      :1711][INFO    ][14434] Returning information for job: 20190530211909546547
2019-05-30 21:19:13,476 [salt.state       :302 ][ERROR   ][8696] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-30 21:19:13,477 [salt.state       :1951][INFO    ][8696] Completed state [maas.wait_for_machine_status] at time 21:19:13.476919 duration_in_ms=2271031.588
2019-05-30 21:19:13,483 [salt.minion      :1711][INFO    ][8696] Returning information for job: 20190530204114303055
2019-05-30 21:19:24,487 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command pillar.get with jid 20190530211924476403
2019-05-30 21:19:24,514 [salt.minion      :1432][INFO    ][14462] Starting a new job with PID 14462
2019-05-30 21:19:24,527 [salt.minion      :1711][INFO    ][14462] Returning information for job: 20190530211924476403
2019-05-30 21:19:25,264 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command service.status with jid 20190530211925251273
2019-05-30 21:19:25,289 [salt.minion      :1432][INFO    ][14467] Starting a new job with PID 14467
2019-05-30 21:19:26,181 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14467] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-30 21:19:26,225 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14467] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-30 21:19:26,248 [salt.minion      :1711][INFO    ][14467] Returning information for job: 20190530211925251273
2019-05-30 21:19:26,957 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530211926943334
2019-05-30 21:19:26,988 [salt.minion      :1432][INFO    ][14478] Starting a new job with PID 14478
2019-05-30 21:19:32,980 [salt.state       :915 ][INFO    ][14478] Loading fresh modules for state activity
2019-05-30 21:19:33,580 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command 'salt-minion --version' in directory '/root'
2019-05-30 21:19:33,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command 'salt-minion --version' in directory '/root'
2019-05-30 21:19:35,022 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command 'salt-minion --version' in directory '/root'
2019-05-30 21:19:35,386 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command 'salt-minion --version' in directory '/root'
2019-05-30 21:19:37,736 [salt.state       :1780][INFO    ][14478] Running state [salt-minion] at time 21:19:37.736498
2019-05-30 21:19:37,736 [salt.state       :1813][INFO    ][14478] Executing state pkg.installed for [salt-minion]
2019-05-30 21:19:37,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-30 21:19:37,860 [salt.state       :300 ][INFO    ][14478] All specified packages are already installed
2019-05-30 21:19:37,860 [salt.state       :1951][INFO    ][14478] Completed state [salt-minion] at time 21:19:37.860450 duration_in_ms=123.953
2019-05-30 21:19:37,860 [salt.state       :1780][INFO    ][14478] Running state [salt_minion_dependency_packages] at time 21:19:37.860824
2019-05-30 21:19:37,861 [salt.state       :1813][INFO    ][14478] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-30 21:19:37,870 [salt.state       :300 ][INFO    ][14478] All specified packages are already installed
2019-05-30 21:19:37,871 [salt.state       :1951][INFO    ][14478] Completed state [salt_minion_dependency_packages] at time 21:19:37.871061 duration_in_ms=10.237
2019-05-30 21:19:37,874 [salt.state       :1780][INFO    ][14478] Running state [/etc/salt/minion.d/minion.conf] at time 21:19:37.874813
2019-05-30 21:19:37,875 [salt.state       :1813][INFO    ][14478] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-30 21:19:38,152 [salt.state       :300 ][INFO    ][14478] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-30 21:19:38,152 [salt.state       :1951][INFO    ][14478] Completed state [/etc/salt/minion.d/minion.conf] at time 21:19:38.152366 duration_in_ms=277.553
2019-05-30 21:19:38,152 [salt.state       :1780][INFO    ][14478] Running state [python-netaddr] at time 21:19:38.152661
2019-05-30 21:19:38,152 [salt.state       :1813][INFO    ][14478] Executing state pkg.installed for [python-netaddr]
2019-05-30 21:19:38,163 [salt.state       :300 ][INFO    ][14478] All specified packages are already installed
2019-05-30 21:19:38,164 [salt.state       :1951][INFO    ][14478] Completed state [python-netaddr] at time 21:19:38.164000 duration_in_ms=11.339
2019-05-30 21:19:38,167 [salt.state       :1780][INFO    ][14478] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:19:38.167654
2019-05-30 21:19:38,167 [salt.state       :1813][INFO    ][14478] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-30 21:19:38,187 [salt.state       :300 ][INFO    ][14478] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-30 21:19:38,187 [salt.state       :1951][INFO    ][14478] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 21:19:38.187852 duration_in_ms=20.197
2019-05-30 21:19:38,189 [salt.state       :1780][INFO    ][14478] Running state [salt-minion] at time 21:19:38.189372
2019-05-30 21:19:38,190 [salt.state       :1813][INFO    ][14478] Executing state service.running for [salt-minion]
2019-05-30 21:19:38,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-30 21:19:38,242 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-30 21:19:38,263 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-30 21:19:38,284 [salt.state       :300 ][INFO    ][14478] The service salt-minion is already running
2019-05-30 21:19:38,284 [salt.state       :1951][INFO    ][14478] Completed state [salt-minion] at time 21:19:38.284808 duration_in_ms=95.435
2019-05-30 21:19:38,288 [salt.state       :1780][INFO    ][14478] Running state [/etc/salt/grains.d] at time 21:19:38.287968
2019-05-30 21:19:38,288 [salt.state       :1813][INFO    ][14478] Executing state file.directory for [/etc/salt/grains.d]
2019-05-30 21:19:38,290 [salt.state       :300 ][INFO    ][14478] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-30 21:19:38,291 [salt.state       :1951][INFO    ][14478] Completed state [/etc/salt/grains.d] at time 21:19:38.291319 duration_in_ms=3.351
2019-05-30 21:19:38,292 [salt.state       :1780][INFO    ][14478] Running state [/etc/salt/grains] at time 21:19:38.292730
2019-05-30 21:19:38,293 [salt.state       :1813][INFO    ][14478] Executing state file.managed for [/etc/salt/grains]
2019-05-30 21:19:38,295 [salt.state       :300 ][INFO    ][14478] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-30 21:19:38,296 [salt.state       :1951][INFO    ][14478] Completed state [/etc/salt/grains] at time 21:19:38.296064 duration_in_ms=3.334
2019-05-30 21:19:38,296 [salt.state       :1780][INFO    ][14478] Running state [/etc/salt/grains.d/placeholder] at time 21:19:38.296550
2019-05-30 21:19:38,296 [salt.state       :1813][INFO    ][14478] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-30 21:19:38,297 [salt.state       :300 ][INFO    ][14478] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-30 21:19:38,298 [salt.state       :1951][INFO    ][14478] Completed state [/etc/salt/grains.d/placeholder] at time 21:19:38.297710 duration_in_ms=1.159
2019-05-30 21:19:38,298 [salt.state       :1780][INFO    ][14478] Running state [/etc/salt/grains.d/sphinx] at time 21:19:38.298571
2019-05-30 21:19:38,298 [salt.state       :1813][INFO    ][14478] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-30 21:19:38,300 [salt.state       :300 ][INFO    ][14478] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-30 21:19:38,300 [salt.state       :1951][INFO    ][14478] Completed state [/etc/salt/grains.d/sphinx] at time 21:19:38.300558 duration_in_ms=1.987
2019-05-30 21:19:38,302 [salt.state       :1780][INFO    ][14478] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:19:38.302792
2019-05-30 21:19:38,303 [salt.state       :1813][INFO    ][14478] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-30 21:19:38,303 [salt.state       :300 ][INFO    ][14478] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-30 21:19:38,303 [salt.state       :1951][INFO    ][14478] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 21:19:38.303628 duration_in_ms=0.836
2019-05-30 21:19:38,304 [salt.state       :1780][INFO    ][14478] Running state [/etc/salt/grains.d/dns_records] at time 21:19:38.304135
2019-05-30 21:19:38,304 [salt.state       :1813][INFO    ][14478] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-30 21:19:38,305 [salt.state       :300 ][INFO    ][14478] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-30 21:19:38,306 [salt.state       :1951][INFO    ][14478] Completed state [/etc/salt/grains.d/dns_records] at time 21:19:38.306662 duration_in_ms=2.527
2019-05-30 21:19:38,307 [salt.state       :1780][INFO    ][14478] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:19:38.307656
2019-05-30 21:19:38,308 [salt.state       :1813][INFO    ][14478] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-30 21:19:38,308 [salt.state       :300 ][INFO    ][14478] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-30 21:19:38,308 [salt.state       :1951][INFO    ][14478] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 21:19:38.308567 duration_in_ms=0.911
2019-05-30 21:19:38,309 [salt.state       :1780][INFO    ][14478] Running state [/etc/salt/grains.d/salt] at time 21:19:38.309103
2019-05-30 21:19:38,309 [salt.state       :1813][INFO    ][14478] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-30 21:19:38,310 [salt.state       :300 ][INFO    ][14478] File /etc/salt/grains.d/salt is in the correct state
2019-05-30 21:19:38,310 [salt.state       :1951][INFO    ][14478] Completed state [/etc/salt/grains.d/salt] at time 21:19:38.310851 duration_in_ms=1.748
2019-05-30 21:19:38,311 [salt.state       :1780][INFO    ][14478] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:19:38.311785
2019-05-30 21:19:38,312 [salt.state       :1813][INFO    ][14478] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-30 21:19:38,312 [salt.state       :300 ][INFO    ][14478] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-30 21:19:38,312 [salt.state       :1951][INFO    ][14478] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 21:19:38.312673 duration_in_ms=0.888
2019-05-30 21:19:38,315 [salt.state       :1780][INFO    ][14478] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:19:38.315525
2019-05-30 21:19:38,315 [salt.state       :1813][INFO    ][14478] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-30 21:19:38,316 [salt.state       :300 ][INFO    ][14478] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-30 21:19:38,316 [salt.state       :1951][INFO    ][14478] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 21:19:38.316359 duration_in_ms=0.834
2019-05-30 21:19:38,317 [salt.state       :1780][INFO    ][14478] Running state [mine.update] at time 21:19:38.317046
2019-05-30 21:19:38,317 [salt.state       :1813][INFO    ][14478] Executing state module.wait for [mine.update]
2019-05-30 21:19:38,317 [salt.state       :300 ][INFO    ][14478] No changes made for mine.update
2019-05-30 21:19:38,318 [salt.state       :1951][INFO    ][14478] Completed state [mine.update] at time 21:19:38.318442 duration_in_ms=1.396
2019-05-30 21:19:38,318 [salt.state       :1780][INFO    ][14478] Running state [ca-certificates] at time 21:19:38.318731
2019-05-30 21:19:38,319 [salt.state       :1813][INFO    ][14478] Executing state pkg.installed for [ca-certificates]
2019-05-30 21:19:38,329 [salt.state       :300 ][INFO    ][14478] All specified packages are already installed
2019-05-30 21:19:38,330 [salt.state       :1951][INFO    ][14478] Completed state [ca-certificates] at time 21:19:38.330343 duration_in_ms=11.611
2019-05-30 21:19:38,331 [salt.state       :1780][INFO    ][14478] Running state [update-ca-certificates] at time 21:19:38.331389
2019-05-30 21:19:38,331 [salt.state       :1813][INFO    ][14478] Executing state cmd.wait for [update-ca-certificates]
2019-05-30 21:19:38,331 [salt.state       :300 ][INFO    ][14478] No changes made for update-ca-certificates
2019-05-30 21:19:38,332 [salt.state       :1951][INFO    ][14478] Completed state [update-ca-certificates] at time 21:19:38.332168 duration_in_ms=0.779
2019-05-30 21:19:38,332 [salt.state       :1780][INFO    ][14478] Running state [iptables] at time 21:19:38.332431
2019-05-30 21:19:38,332 [salt.state       :1813][INFO    ][14478] Executing state pkg.installed for [iptables]
2019-05-30 21:19:38,342 [salt.state       :300 ][INFO    ][14478] All specified packages are already installed
2019-05-30 21:19:38,343 [salt.state       :1951][INFO    ][14478] Completed state [iptables] at time 21:19:38.343026 duration_in_ms=10.595
2019-05-30 21:19:38,343 [salt.state       :1780][INFO    ][14478] Running state [iptables-persistent] at time 21:19:38.343293
2019-05-30 21:19:38,343 [salt.state       :1813][INFO    ][14478] Executing state pkg.installed for [iptables-persistent]
2019-05-30 21:19:38,352 [salt.state       :300 ][INFO    ][14478] All specified packages are already installed
2019-05-30 21:19:38,352 [salt.state       :1951][INFO    ][14478] Completed state [iptables-persistent] at time 21:19:38.352842 duration_in_ms=9.549
2019-05-30 21:19:38,355 [salt.state       :1780][INFO    ][14478] Running state [iptables_modules_v4_load] at time 21:19:38.355013
2019-05-30 21:19:38,355 [salt.state       :1813][INFO    ][14478] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-30 21:19:38,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command 'lsmod' in directory '/root'
2019-05-30 21:19:38,382 [salt.state       :300 ][INFO    ][14478] Kernel modules iptable_filter, ip_tables are already present
2019-05-30 21:19:38,383 [salt.state       :1951][INFO    ][14478] Completed state [iptables_modules_v4_load] at time 21:19:38.382881 duration_in_ms=27.866
2019-05-30 21:19:38,384 [salt.state       :1780][INFO    ][14478] Running state [/etc/iptables/rules.v4] at time 21:19:38.384236
2019-05-30 21:19:38,384 [salt.state       :1813][INFO    ][14478] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-30 21:19:38,503 [salt.state       :300 ][INFO    ][14478] File /etc/iptables/rules.v4 is in the correct state
2019-05-30 21:19:38,503 [salt.state       :1951][INFO    ][14478] Completed state [/etc/iptables/rules.v4] at time 21:19:38.503798 duration_in_ms=119.562
2019-05-30 21:19:38,504 [salt.state       :1780][INFO    ][14478] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:19:38.504901
2019-05-30 21:19:38,505 [salt.state       :1813][INFO    ][14478] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-30 21:19:38,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-30 21:19:38,526 [salt.state       :300 ][INFO    ][14478] onlyif execution failed
2019-05-30 21:19:38,527 [salt.state       :1951][INFO    ][14478] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 21:19:38.526959 duration_in_ms=22.057
2019-05-30 21:19:38,529 [salt.state       :1780][INFO    ][14478] Running state [netfilter-persistent] at time 21:19:38.529094
2019-05-30 21:19:38,529 [salt.state       :1813][INFO    ][14478] Executing state service.running for [netfilter-persistent]
2019-05-30 21:19:38,531 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-30 21:19:38,551 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-30 21:19:38,573 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-30 21:19:38,596 [salt.state       :300 ][INFO    ][14478] The service netfilter-persistent is already running
2019-05-30 21:19:38,596 [salt.state       :1951][INFO    ][14478] Completed state [netfilter-persistent] at time 21:19:38.596654 duration_in_ms=67.56
2019-05-30 21:19:38,598 [salt.state       :1780][INFO    ][14478] Running state [iptables_extra.remove_stale_tables] at time 21:19:38.598294
2019-05-30 21:19:38,598 [salt.state       :1813][INFO    ][14478] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-30 21:19:38,599 [salt.state       :300 ][INFO    ][14478] No changes made for iptables_extra.remove_stale_tables
2019-05-30 21:19:38,600 [salt.state       :1951][INFO    ][14478] Completed state [iptables_extra.remove_stale_tables] at time 21:19:38.599935 duration_in_ms=1.642
2019-05-30 21:19:38,600 [salt.state       :1780][INFO    ][14478] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:19:38.600449
2019-05-30 21:19:38,601 [salt.state       :1813][INFO    ][14478] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-30 21:19:38,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14478] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-30 21:19:38,620 [salt.state       :300 ][INFO    ][14478] onlyif execution failed
2019-05-30 21:19:38,621 [salt.state       :1951][INFO    ][14478] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 21:19:38.621514 duration_in_ms=21.065
2019-05-30 21:19:38,624 [salt.state       :1780][INFO    ][14478] Running state [/etc/iptables/rules.v6] at time 21:19:38.623938
2019-05-30 21:19:38,624 [salt.state       :1813][INFO    ][14478] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-30 21:19:38,625 [salt.state       :300 ][INFO    ][14478] File /etc/iptables/rules.v6 is not present
2019-05-30 21:19:38,626 [salt.state       :1951][INFO    ][14478] Completed state [/etc/iptables/rules.v6] at time 21:19:38.625902 duration_in_ms=1.963
2019-05-30 21:19:38,627 [salt.state       :1780][INFO    ][14478] Running state [iptables_extra.flush_all] at time 21:19:38.627318
2019-05-30 21:19:38,627 [salt.state       :1813][INFO    ][14478] Executing state module.wait for [iptables_extra.flush_all]
2019-05-30 21:19:38,628 [salt.state       :300 ][INFO    ][14478] No changes made for iptables_extra.flush_all
2019-05-30 21:19:38,629 [salt.state       :1951][INFO    ][14478] Completed state [iptables_extra.flush_all] at time 21:19:38.628933 duration_in_ms=1.616
2019-05-30 21:19:38,634 [salt.minion      :1711][INFO    ][14478] Returning information for job: 20190530211926943334
2019-05-30 21:19:39,403 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530211939390110
2019-05-30 21:19:39,432 [salt.minion      :1432][INFO    ][14565] Starting a new job with PID 14565
2019-05-30 21:19:40,373 [salt.state       :915 ][INFO    ][14565] Loading fresh modules for state activity
2019-05-30 21:19:41,670 [salt.state       :1780][INFO    ][14565] Running state [maas-rack-controller] at time 21:19:41.670768
2019-05-30 21:19:41,671 [salt.state       :1813][INFO    ][14565] Executing state pkg.installed for [maas-rack-controller]
2019-05-30 21:19:41,671 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14565] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-30 21:19:41,800 [salt.state       :300 ][INFO    ][14565] All specified packages are already installed
2019-05-30 21:19:41,800 [salt.state       :1951][INFO    ][14565] Completed state [maas-rack-controller] at time 21:19:41.800526 duration_in_ms=129.76
2019-05-30 21:19:41,801 [salt.state       :1780][INFO    ][14565] Running state [ipmitool] at time 21:19:41.801441
2019-05-30 21:19:41,801 [salt.state       :1813][INFO    ][14565] Executing state pkg.installed for [ipmitool]
2019-05-30 21:19:41,811 [salt.state       :300 ][INFO    ][14565] All specified packages are already installed
2019-05-30 21:19:41,811 [salt.state       :1951][INFO    ][14565] Completed state [ipmitool] at time 21:19:41.811729 duration_in_ms=10.288
2019-05-30 21:19:41,814 [salt.state       :1780][INFO    ][14565] Running state [/etc/maas/rackd.conf] at time 21:19:41.814599
2019-05-30 21:19:41,814 [salt.state       :1813][INFO    ][14565] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-30 21:19:41,815 [salt.state       :300 ][INFO    ][14565] No changes needed to be made
2019-05-30 21:19:41,816 [salt.state       :1951][INFO    ][14565] Completed state [/etc/maas/rackd.conf] at time 21:19:41.816088 duration_in_ms=1.488
2019-05-30 21:19:41,816 [salt.state       :1780][INFO    ][14565] Running state [/etc/maas/rackd.conf] at time 21:19:41.816371
2019-05-30 21:19:41,816 [salt.state       :1813][INFO    ][14565] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-30 21:19:41,816 [salt.loaded.int.states.file:2298][WARNING ][14565] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-30 21:19:41,818 [salt.state       :300 ][INFO    ][14565] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-30 21:19:41,818 [salt.state       :1951][INFO    ][14565] Completed state [/etc/maas/rackd.conf] at time 21:19:41.818185 duration_in_ms=1.814
2019-05-30 21:19:41,819 [salt.state       :1780][INFO    ][14565] Running state [maas-rackd] at time 21:19:41.819218
2019-05-30 21:19:41,819 [salt.state       :1813][INFO    ][14565] Executing state service.running for [maas-rackd]
2019-05-30 21:19:41,820 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14565] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-30 21:19:41,865 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14565] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-30 21:19:41,885 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14565] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-30 21:19:41,910 [salt.state       :300 ][INFO    ][14565] The service maas-rackd is already running
2019-05-30 21:19:41,911 [salt.state       :1951][INFO    ][14565] Completed state [maas-rackd] at time 21:19:41.911126 duration_in_ms=91.906
2019-05-30 21:19:41,913 [salt.minion      :1711][INFO    ][14565] Returning information for job: 20190530211939390110
2019-05-30 21:19:42,676 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530211942663253
2019-05-30 21:19:42,703 [salt.minion      :1432][INFO    ][14589] Starting a new job with PID 14589
2019-05-30 21:19:43,730 [salt.state       :915 ][INFO    ][14589] Loading fresh modules for state activity
2019-05-30 21:19:45,284 [salt.state       :1780][INFO    ][14589] Running state [maas-region-controller] at time 21:19:45.284577
2019-05-30 21:19:45,285 [salt.state       :1813][INFO    ][14589] Executing state pkg.installed for [maas-region-controller]
2019-05-30 21:19:45,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-30 21:19:45,399 [salt.state       :300 ][INFO    ][14589] All specified packages are already installed
2019-05-30 21:19:45,400 [salt.state       :1951][INFO    ][14589] Completed state [maas-region-controller] at time 21:19:45.400149 duration_in_ms=115.572
2019-05-30 21:19:45,400 [salt.state       :1780][INFO    ][14589] Running state [python-oauth] at time 21:19:45.400505
2019-05-30 21:19:45,400 [salt.state       :1813][INFO    ][14589] Executing state pkg.installed for [python-oauth]
2019-05-30 21:19:45,410 [salt.state       :300 ][INFO    ][14589] All specified packages are already installed
2019-05-30 21:19:45,411 [salt.state       :1951][INFO    ][14589] Completed state [python-oauth] at time 21:19:45.410939 duration_in_ms=10.434
2019-05-30 21:19:45,414 [salt.state       :1780][INFO    ][14589] Running state [/etc/maas/regiond.conf] at time 21:19:45.413981
2019-05-30 21:19:45,414 [salt.state       :1813][INFO    ][14589] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-30 21:19:45,419 [salt.state       :300 ][INFO    ][14589] No changes needed to be made
2019-05-30 21:19:45,419 [salt.state       :1951][INFO    ][14589] Completed state [/etc/maas/regiond.conf] at time 21:19:45.419619 duration_in_ms=5.637
2019-05-30 21:19:45,420 [salt.state       :1780][INFO    ][14589] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:19:45.420337
2019-05-30 21:19:45,420 [salt.state       :1813][INFO    ][14589] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-30 21:19:45,478 [salt.state       :300 ][INFO    ][14589] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-30 21:19:45,478 [salt.state       :1951][INFO    ][14589] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:19:45.478721 duration_in_ms=58.383
2019-05-30 21:19:45,479 [salt.state       :1780][INFO    ][14589] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:45.479294
2019-05-30 21:19:45,479 [salt.state       :1813][INFO    ][14589] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-30 21:19:45,485 [salt.state       :300 ][INFO    ][14589] No changes needed to be made
2019-05-30 21:19:45,485 [salt.state       :1951][INFO    ][14589] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:45.485254 duration_in_ms=5.961
2019-05-30 21:19:45,485 [salt.state       :1780][INFO    ][14589] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:45.485770
2019-05-30 21:19:45,486 [salt.state       :1813][INFO    ][14589] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-30 21:19:45,490 [salt.state       :300 ][INFO    ][14589] No changes needed to be made
2019-05-30 21:19:45,490 [salt.state       :1951][INFO    ][14589] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:45.490552 duration_in_ms=4.782
2019-05-30 21:19:45,491 [salt.state       :1780][INFO    ][14589] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:19:45.491061
2019-05-30 21:19:45,491 [salt.state       :1813][INFO    ][14589] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-30 21:19:45,515 [salt.state       :300 ][INFO    ][14589] No changes needed to be made
2019-05-30 21:19:45,515 [salt.state       :1951][INFO    ][14589] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:19:45.515592 duration_in_ms=24.53
2019-05-30 21:19:45,516 [salt.state       :1780][INFO    ][14589] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:19:45.516100
2019-05-30 21:19:45,516 [salt.state       :1813][INFO    ][14589] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-30 21:19:45,528 [salt.state       :300 ][INFO    ][14589] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-30 21:19:45,529 [salt.state       :1951][INFO    ][14589] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:19:45.528946 duration_in_ms=12.846
2019-05-30 21:19:45,531 [salt.state       :1780][INFO    ][14589] Running state [a2enmod headers] at time 21:19:45.531453
2019-05-30 21:19:45,531 [salt.state       :1813][INFO    ][14589] Executing state cmd.run for [a2enmod headers]
2019-05-30 21:19:45,532 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command 'a2enmod headers' in directory '/root'
2019-05-30 21:19:45,596 [salt.state       :300 ][INFO    ][14589] {'pid': 14608, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-30 21:19:45,597 [salt.state       :1951][INFO    ][14589] Completed state [a2enmod headers] at time 21:19:45.597116 duration_in_ms=65.662
2019-05-30 21:19:45,597 [salt.state       :1780][INFO    ][14589] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:19:45.597806
2019-05-30 21:19:45,598 [salt.state       :1813][INFO    ][14589] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-30 21:19:45,620 [salt.state       :300 ][INFO    ][14589] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-30 21:19:45,620 [salt.state       :1951][INFO    ][14589] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:19:45.620287 duration_in_ms=22.481
2019-05-30 21:19:45,620 [salt.state       :1780][INFO    ][14589] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:19:45.620917
2019-05-30 21:19:45,621 [salt.state       :1813][INFO    ][14589] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-30 21:19:45,675 [salt.state       :300 ][INFO    ][14589] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-30 21:19:45,675 [salt.state       :1951][INFO    ][14589] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:19:45.675516 duration_in_ms=54.599
2019-05-30 21:19:45,676 [salt.state       :1780][INFO    ][14589] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:19:45.676072
2019-05-30 21:19:45,676 [salt.state       :1813][INFO    ][14589] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-30 21:19:45,727 [salt.state       :300 ][INFO    ][14589] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-30 21:19:45,727 [salt.state       :1951][INFO    ][14589] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:19:45.727864 duration_in_ms=51.792
2019-05-30 21:19:45,728 [salt.state       :1780][INFO    ][14589] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:19:45.728377
2019-05-30 21:19:45,728 [salt.state       :1813][INFO    ][14589] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-30 21:19:45,794 [salt.state       :300 ][INFO    ][14589] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-30 21:19:45,795 [salt.state       :1951][INFO    ][14589] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:19:45.795228 duration_in_ms=66.849
2019-05-30 21:19:45,796 [salt.state       :1780][INFO    ][14589] Running state [/root/.pgpass] at time 21:19:45.795936
2019-05-30 21:19:45,796 [salt.state       :1813][INFO    ][14589] Executing state file.managed for [/root/.pgpass]
2019-05-30 21:19:45,850 [salt.state       :300 ][INFO    ][14589] File /root/.pgpass is in the correct state
2019-05-30 21:19:45,851 [salt.state       :1951][INFO    ][14589] Completed state [/root/.pgpass] at time 21:19:45.851340 duration_in_ms=55.404
2019-05-30 21:19:45,858 [salt.state       :1780][INFO    ][14589] Running state [maas-region syncdb --noinput] at time 21:19:45.858926
2019-05-30 21:19:45,859 [salt.state       :1813][INFO    ][14589] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-30 21:19:45,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-30 21:19:48,522 [salt.state       :300 ][INFO    ][14589] {'pid': 14621, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, contenttypes, sites, piston3, metadataserver, auth, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-30 21:19:48,523 [salt.state       :1951][INFO    ][14589] Completed state [maas-region syncdb --noinput] at time 21:19:48.523639 duration_in_ms=2664.711
2019-05-30 21:19:48,524 [salt.state       :2022][WARNING ][14589] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-30 21:19:48,527 [salt.state       :1780][INFO    ][14589] Running state [maas-regiond] at time 21:19:48.527098
2019-05-30 21:19:48,527 [salt.state       :1813][INFO    ][14589] Executing state service.running for [maas-regiond]
2019-05-30 21:19:48,529 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-30 21:19:48,570 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-30 21:19:48,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-30 21:19:48,612 [salt.state       :300 ][INFO    ][14589] The service maas-regiond is already running
2019-05-30 21:19:48,613 [salt.state       :1951][INFO    ][14589] Completed state [maas-regiond] at time 21:19:48.613006 duration_in_ms=85.908
2019-05-30 21:19:48,617 [salt.state       :1780][INFO    ][14589] Running state [bind9] at time 21:19:48.617090
2019-05-30 21:19:48,617 [salt.state       :1813][INFO    ][14589] Executing state service.running for [bind9]
2019-05-30 21:19:48,618 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-30 21:19:48,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-30 21:19:48,666 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-30 21:19:48,690 [salt.state       :300 ][INFO    ][14589] The service bind9 is already running
2019-05-30 21:19:48,690 [salt.state       :1951][INFO    ][14589] Completed state [bind9] at time 21:19:48.690557 duration_in_ms=73.467
2019-05-30 21:19:48,692 [salt.state       :1780][INFO    ][14589] Running state [apache2] at time 21:19:48.692313
2019-05-30 21:19:48,692 [salt.state       :1813][INFO    ][14589] Executing state service.running for [apache2]
2019-05-30 21:19:48,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-30 21:19:48,717 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-30 21:19:48,738 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-30 21:19:48,767 [salt.state       :300 ][INFO    ][14589] The service apache2 is already running
2019-05-30 21:19:48,767 [salt.state       :1951][INFO    ][14589] Completed state [apache2] at time 21:19:48.767826 duration_in_ms=75.512
2019-05-30 21:19:48,773 [salt.state       :1780][INFO    ][14589] Running state [maasng.wait_for_http_code] at time 21:19:48.772928
2019-05-30 21:19:48,773 [salt.state       :1813][INFO    ][14589] Executing state module.run for [maasng.wait_for_http_code]
2019-05-30 21:19:48,774 [salt.utils.decorators:613 ][WARNING ][14589] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 21:19:48,923 [salt.state       :300 ][INFO    ][14589] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-30 21:19:48,924 [salt.state       :1951][INFO    ][14589] Completed state [maasng.wait_for_http_code] at time 21:19:48.924137 duration_in_ms=151.208
2019-05-30 21:19:48,925 [salt.state       :1780][INFO    ][14589] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:19:48.925009
2019-05-30 21:19:48,925 [salt.state       :1813][INFO    ][14589] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-30 21:19:48,928 [salt.state       :300 ][INFO    ][14589] /var/lib/maas/.setup_admin exists
2019-05-30 21:19:48,929 [salt.state       :1951][INFO    ][14589] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:19:48.929094 duration_in_ms=4.085
2019-05-30 21:19:48,929 [salt.state       :1780][INFO    ][14589] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:19:48.929815
2019-05-30 21:19:48,930 [salt.state       :1813][INFO    ][14589] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 21:19:48,930 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14589] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 21:19:50,942 [salt.state       :300 ][INFO    ][14589] {'pid': 14648, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 21:19:50,943 [salt.state       :1951][INFO    ][14589] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:19:50.943747 duration_in_ms=2013.931
2019-05-30 21:19:50,952 [salt.state       :1780][INFO    ][14589] Running state [maas_region_boot_source_resources_mirror] at time 21:19:50.952106
2019-05-30 21:19:50,952 [salt.state       :1813][INFO    ][14589] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-30 21:19:51,036 [salt.state       :300 ][INFO    ][14589] {'changes': {}}
2019-05-30 21:19:51,036 [salt.state       :1951][INFO    ][14589] Completed state [maas_region_boot_source_resources_mirror] at time 21:19:51.036868 duration_in_ms=84.761
2019-05-30 21:19:51,039 [salt.state       :1780][INFO    ][14589] Running state [maasng.boot_resources_import] at time 21:19:51.038989
2019-05-30 21:19:51,039 [salt.state       :1813][INFO    ][14589] Executing state module.run for [maasng.boot_resources_import]
2019-05-30 21:19:51,040 [salt.utils.decorators:613 ][WARNING ][14589] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 21:19:51,130 [salt.loaded.ext.module.maasng:1600][INFO    ][14589] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-30 21:19:56,188 [salt.loaded.ext.module.maasng:1600][INFO    ][14589] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-30 21:19:57,778 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530211957768103
2019-05-30 21:19:57,810 [salt.minion      :1432][INFO    ][14680] Starting a new job with PID 14680
2019-05-30 21:19:57,850 [salt.minion      :1711][INFO    ][14680] Returning information for job: 20190530211957768103
2019-05-30 21:20:01,247 [salt.loaded.ext.module.maasng:1600][INFO    ][14589] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-30 21:20:06,345 [salt.state       :300 ][INFO    ][14589] {'ret': True}
2019-05-30 21:20:06,346 [salt.state       :1951][INFO    ][14589] Completed state [maasng.boot_resources_import] at time 21:20:06.346305 duration_in_ms=15307.316
2019-05-30 21:20:06,347 [salt.state       :1780][INFO    ][14589] Running state [maas_region_boot_sources_selection_xenial] at time 21:20:06.347663
2019-05-30 21:20:06,348 [salt.state       :1813][INFO    ][14589] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-30 21:20:06,528 [salt.state       :300 ][INFO    ][14589] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-30 21:20:06,528 [salt.state       :1951][INFO    ][14589] Completed state [maas_region_boot_sources_selection_xenial] at time 21:20:06.528740 duration_in_ms=181.077
2019-05-30 21:20:06,531 [salt.state       :1780][INFO    ][14589] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:20:06.531364
2019-05-30 21:20:06,531 [salt.state       :1813][INFO    ][14589] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-30 21:20:06,532 [salt.utils.decorators:613 ][WARNING ][14589] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 21:20:06,533 [salt.loaded.ext.module.maasng:1771][INFO    ][14589] boot-sources sync initiated for ALL Rack's
2019-05-30 21:20:07,598 [salt.state       :300 ][INFO    ][14589] {'ret': True}
2019-05-30 21:20:07,599 [salt.state       :1951][INFO    ][14589] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:20:07.599218 duration_in_ms=1067.853
2019-05-30 21:20:07,601 [salt.state       :1780][INFO    ][14589] Running state [maas.process_maas_config] at time 21:20:07.601134
2019-05-30 21:20:07,601 [salt.state       :1813][INFO    ][14589] Executing state module.run for [maas.process_maas_config]
2019-05-30 21:20:07,602 [salt.utils.decorators:613 ][WARNING ][14589] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 21:20:07,603 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=enable_http_proxy value=True
2019-05-30 21:20:07,657 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=upstream_dns value=8.8.8.8
2019-05-30 21:20:07,721 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=commissioning_distro_series value=xenial
2019-05-30 21:20:07,789 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=default_osystem value=ubuntu
2019-05-30 21:20:07,848 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=active_discovery_interval value=600
2019-05-30 21:20:07,897 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=dnssec_validation value=no
2019-05-30 21:20:10,529 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=maas_name value=mas01
2019-05-30 21:20:10,595 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=network_discovery value=enabled
2019-05-30 21:20:10,727 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=enable_third_party_drivers value=True
2019-05-30 21:20:10,791 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=default_storage_layout value=lvm
2019-05-30 21:20:10,839 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=ntp_external_only value=True
2019-05-30 21:20:10,891 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-30 21:20:10,939 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=default_distro_series value=xenial
2019-05-30 21:20:11,002 [salt.loaded.ext.module.maas:92  ][INFO    ][14589] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-30 21:20:11,116 [salt.state       :300 ][INFO    ][14589] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-30 21:20:11,116 [salt.state       :1951][INFO    ][14589] Completed state [maas.process_maas_config] at time 21:20:11.116339 duration_in_ms=3515.205
2019-05-30 21:20:11,117 [salt.state       :1780][INFO    ][14589] Running state [pxe_admin] at time 21:20:11.117155
2019-05-30 21:20:11,117 [salt.state       :1813][INFO    ][14589] Executing state maasng.fabric_present for [pxe_admin]
2019-05-30 21:20:11,177 [salt.loaded.ext.module.maasng:945 ][INFO    ][14589] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'6mdsex', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}]
2019-05-30 21:20:11,240 [salt.loaded.ext.module.maasng:1008][WARNING ][14589] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-30 21:20:11,240 [salt.loaded.ext.module.maasng:1011][WARNING ][14589] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-30 21:20:11,304 [salt.state       :300 ][INFO    ][14589] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-30 21:20:11,304 [salt.state       :1951][INFO    ][14589] Completed state [pxe_admin] at time 21:20:11.304828 duration_in_ms=187.673
2019-05-30 21:20:11,305 [salt.state       :1780][INFO    ][14589] Running state [vlan 0] at time 21:20:11.305177
2019-05-30 21:20:11,305 [salt.state       :1813][INFO    ][14589] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-30 21:20:11,365 [salt.loaded.ext.module.maasng:945 ][INFO    ][14589] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'fabric': u'fabric-4'}], u'id': 4, u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6mdsex', u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 3, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}]
2019-05-30 21:20:11,463 [salt.loaded.ext.module.maasng:945 ][INFO    ][14589] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 4, u'vlans': [{u'fabric': u'fabric-4', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': None}, {u'id': 3, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': u''}]
2019-05-30 21:20:11,706 [salt.loaded.ext.module.maasng:945 ][INFO    ][14589] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 4, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': None, u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}, {u'id': 3, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}]
2019-05-30 21:20:11,784 [salt.state       :300 ][INFO    ][14589] {'new': 'Vlan untagged was updated'}
2019-05-30 21:20:11,784 [salt.state       :1951][INFO    ][14589] Completed state [vlan 0] at time 21:20:11.784897 duration_in_ms=479.719
2019-05-30 21:20:11,787 [salt.state       :1780][INFO    ][14589] Running state [192.168.11.0/24] at time 21:20:11.787285
2019-05-30 21:20:11,787 [salt.state       :1813][INFO    ][14589] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-30 21:20:11,956 [salt.loaded.ext.module.maasng:945 ][INFO    ][14589] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 4, u'vlans': [{u'fabric': u'fabric-4', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': None}, {u'id': 3, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'6mdsex', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': u''}]
2019-05-30 21:20:11,957 [salt.loaded.ext.module.maasng:1235][WARNING ][14589] Ignoring parameter vlan:0
2019-05-30 21:20:12,054 [salt.state       :300 ][INFO    ][14589] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-30 21:20:12,054 [salt.state       :1951][INFO    ][14589] Completed state [192.168.11.0/24] at time 21:20:12.054783 duration_in_ms=267.497
2019-05-30 21:20:12,055 [salt.state       :1780][INFO    ][14589] Running state [maas_create_iprange_1] at time 21:20:12.055893
2019-05-30 21:20:12,056 [salt.state       :1813][INFO    ][14589] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-30 21:20:12,119 [salt.state       :300 ][INFO    ][14589] Iprange maas_create_iprange_1 already exist.
2019-05-30 21:20:12,119 [salt.state       :1951][INFO    ][14589] Completed state [maas_create_iprange_1] at time 21:20:12.119652 duration_in_ms=63.759
2019-05-30 21:20:12,120 [salt.state       :1780][INFO    ][14589] Running state [vlan 0] at time 21:20:12.120110
2019-05-30 21:20:12,120 [salt.state       :1813][INFO    ][14589] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-30 21:20:12,305 [salt.loaded.ext.module.maasng:945 ][INFO    ][14589] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 4, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': None, u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}, {u'id': 3, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}]
2019-05-30 21:20:12,390 [salt.loaded.ext.module.maasng:945 ][INFO    ][14589] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'fabric': u'fabric-4'}], u'id': 4, u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'6mdsex', u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 3, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}]
2019-05-30 21:20:12,694 [salt.loaded.ext.module.maasng:945 ][INFO    ][14589] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 4, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': None, u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}, {u'id': 3, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'6mdsex', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}]
2019-05-30 21:20:12,777 [salt.state       :300 ][INFO    ][14589] {'new': 'Vlan untagged was updated'}
2019-05-30 21:20:12,777 [salt.state       :1951][INFO    ][14589] Completed state [vlan 0] at time 21:20:12.777297 duration_in_ms=657.186
2019-05-30 21:20:12,778 [salt.state       :1780][INFO    ][14589] Running state [opnfv] at time 21:20:12.778675
2019-05-30 21:20:12,779 [salt.state       :1813][INFO    ][14589] Executing state maasng.sshkey_present for [opnfv]
2019-05-30 21:20:12,827 [salt.loaded.ext.module.maasng:1903][INFO    ][14589] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-05-30 21:20:12,828 [salt.state       :300 ][INFO    ][14589] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-30 21:20:12,828 [salt.state       :1951][INFO    ][14589] Completed state [opnfv] at time 21:20:12.828452 duration_in_ms=49.777
2019-05-30 21:20:12,834 [salt.minion      :1711][INFO    ][14589] Returning information for job: 20190530211942663253
2019-05-30 21:20:13,631 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530212013620654
2019-05-30 21:20:13,648 [salt.minion      :1432][INFO    ][15043] Starting a new job with PID 15043
2019-05-30 21:20:19,798 [salt.state       :915 ][INFO    ][15043] Loading fresh modules for state activity
2019-05-30 21:20:19,908 [salt.state       :1780][INFO    ][15043] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:20:19.908809
2019-05-30 21:20:19,909 [salt.state       :1813][INFO    ][15043] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 21:20:19,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15043] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 21:20:21,913 [salt.state       :300 ][INFO    ][15043] {'pid': 15081, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 21:20:21,916 [salt.state       :1951][INFO    ][15043] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:20:21.915958 duration_in_ms=2007.149
2019-05-30 21:20:21,918 [salt.state       :1780][INFO    ][15043] Running state [maas.process_machines] at time 21:20:21.918508
2019-05-30 21:20:21,919 [salt.state       :1813][INFO    ][15043] Executing state module.run for [maas.process_machines]
2019-05-30 21:20:21,919 [salt.utils.decorators:613 ][WARNING ][15043] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 21:20:22,515 [salt.loaded.ext.module.maas:412 ][WARNING ][15043] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 21:20:22,516 [salt.loaded.ext.module.maas:92  ][INFO    ][15043] 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=7qqfbn architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 21:20:23,764 [salt.loaded.ext.module.maas:412 ][WARNING ][15043] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 21:20:23,765 [salt.loaded.ext.module.maas:92  ][INFO    ][15043] 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=dpsxd4 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 21:20:24,970 [salt.loaded.ext.module.maas:412 ][WARNING ][15043] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 21:20:24,971 [salt.loaded.ext.module.maas:92  ][INFO    ][15043] 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=awsqdy architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 21:20:26,164 [salt.loaded.ext.module.maas:412 ][WARNING ][15043] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 21:20:26,165 [salt.loaded.ext.module.maas:92  ][INFO    ][15043] 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=h3rcfs architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 21:20:27,384 [salt.loaded.ext.module.maas:412 ][WARNING ][15043] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-30 21:20:27,385 [salt.loaded.ext.module.maas:92  ][INFO    ][15043] 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=pkepyc architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-30 21:20:28,579 [salt.state       :300 ][INFO    ][15043] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-30 21:20:28,580 [salt.state       :1951][INFO    ][15043] Completed state [maas.process_machines] at time 21:20:28.580096 duration_in_ms=6661.587
2019-05-30 21:20:28,584 [salt.minion      :1711][INFO    ][15043] Returning information for job: 20190530212013620654
2019-05-30 21:21:02,721 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530212102710553
2019-05-30 21:21:02,751 [salt.minion      :1432][INFO    ][15352] Starting a new job with PID 15352
2019-05-30 21:21:08,763 [salt.state       :915 ][INFO    ][15352] Loading fresh modules for state activity
2019-05-30 21:21:08,869 [salt.state       :1780][INFO    ][15352] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:08.869413
2019-05-30 21:21:08,870 [salt.state       :1813][INFO    ][15352] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 21:21:08,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15352] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 21:21:10,722 [salt.state       :300 ][INFO    ][15352] {'pid': 15365, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 21:21:10,723 [salt.state       :1951][INFO    ][15352] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:10.723362 duration_in_ms=1853.948
2019-05-30 21:21:10,726 [salt.state       :1780][INFO    ][15352] Running state [maas.wait_for_machine_status] at time 21:21:10.726806
2019-05-30 21:21:10,727 [salt.state       :1813][INFO    ][15352] Executing state module.run for [maas.wait_for_machine_status]
2019-05-30 21:21:10,728 [salt.utils.decorators:613 ][WARNING ][15352] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 21:21:13,488 [salt.state       :300 ][INFO    ][15352] {'ret': True}
2019-05-30 21:21:13,489 [salt.state       :1951][INFO    ][15352] Completed state [maas.wait_for_machine_status] at time 21:21:13.489325 duration_in_ms=2762.517
2019-05-30 21:21:13,495 [salt.minion      :1711][INFO    ][15352] Returning information for job: 20190530212102710553
2019-05-30 21:21:14,252 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530212114235568
2019-05-30 21:21:14,279 [salt.minion      :1432][INFO    ][15378] Starting a new job with PID 15378
2019-05-30 21:21:15,244 [salt.state       :915 ][INFO    ][15378] Loading fresh modules for state activity
2019-05-30 21:21:15,410 [salt.state       :1780][INFO    ][15378] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:15.410614
2019-05-30 21:21:15,411 [salt.state       :1813][INFO    ][15378] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 21:21:15,412 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15378] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 21:21:17,314 [salt.state       :300 ][INFO    ][15378] {'pid': 15385, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 21:21:17,316 [salt.state       :1951][INFO    ][15378] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:17.315963 duration_in_ms=1905.348
2019-05-30 21:21:17,320 [salt.state       :1780][INFO    ][15378] Running state [maas_machines_storage_cmp002_lvm] at time 21:21:17.320693
2019-05-30 21:21:17,321 [salt.state       :1813][INFO    ][15378] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-30 21:21:18,079 [salt.state       :300 ][INFO    ][15378] Machine cmp002 is not in Ready state.
2019-05-30 21:21:18,080 [salt.state       :1951][INFO    ][15378] Completed state [maas_machines_storage_cmp002_lvm] at time 21:21:18.079996 duration_in_ms=759.302
2019-05-30 21:21:18,080 [salt.state       :1780][INFO    ][15378] Running state [maas_machines_storage_cmp001_lvm] at time 21:21:18.080513
2019-05-30 21:21:18,081 [salt.state       :1813][INFO    ][15378] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-30 21:21:18,619 [salt.state       :300 ][INFO    ][15378] Machine cmp001 is not in Ready state.
2019-05-30 21:21:18,620 [salt.state       :1951][INFO    ][15378] Completed state [maas_machines_storage_cmp001_lvm] at time 21:21:18.619899 duration_in_ms=539.384
2019-05-30 21:21:18,624 [salt.minion      :1711][INFO    ][15378] Returning information for job: 20190530212114235568
2019-05-30 21:21:19,378 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530212119365462
2019-05-30 21:21:19,408 [salt.minion      :1432][INFO    ][15411] Starting a new job with PID 15411
2019-05-30 21:21:20,431 [salt.state       :915 ][INFO    ][15411] Loading fresh modules for state activity
2019-05-30 21:21:20,537 [salt.state       :1780][INFO    ][15411] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:20.537565
2019-05-30 21:21:20,538 [salt.state       :1813][INFO    ][15411] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 21:21:20,540 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15411] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 21:21:22,459 [salt.state       :300 ][INFO    ][15411] {'pid': 15419, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 21:21:22,460 [salt.state       :1951][INFO    ][15411] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:22.460072 duration_in_ms=1922.508
2019-05-30 21:21:22,463 [salt.state       :1780][INFO    ][15411] Running state [maas.deploy_machines] at time 21:21:22.463171
2019-05-30 21:21:22,463 [salt.state       :1813][INFO    ][15411] Executing state module.run for [maas.deploy_machines]
2019-05-30 21:21:22,464 [salt.utils.decorators:613 ][WARNING ][15411] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 21:21:23,040 [salt.loaded.ext.module.maas:684 ][INFO    ][15411] deploymachines hwe_kernel=hwe-16.04 system_id=pkepyc distro_series=xenial
2019-05-30 21:21:25,607 [salt.state       :300 ][INFO    ][15411] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-30 21:21:25,608 [salt.state       :1951][INFO    ][15411] Completed state [maas.deploy_machines] at time 21:21:25.608072 duration_in_ms=3144.9
2019-05-30 21:21:25,614 [salt.minion      :1711][INFO    ][15411] Returning information for job: 20190530212119365462
2019-05-30 21:21:26,380 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command state.apply with jid 20190530212126364666
2019-05-30 21:21:26,407 [salt.minion      :1432][INFO    ][15484] Starting a new job with PID 15484
2019-05-30 21:21:32,441 [salt.state       :915 ][INFO    ][15484] Loading fresh modules for state activity
2019-05-30 21:21:32,556 [salt.state       :1780][INFO    ][15484] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:32.556196
2019-05-30 21:21:32,556 [salt.state       :1813][INFO    ][15484] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-30 21:21:32,560 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15484] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-30 21:21:34,427 [salt.state       :300 ][INFO    ][15484] {'pid': 15495, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-30 21:21:34,429 [salt.state       :1951][INFO    ][15484] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:34.428880 duration_in_ms=1872.685
2019-05-30 21:21:34,432 [salt.state       :1780][INFO    ][15484] Running state [maas.wait_for_machine_status] at time 21:21:34.432731
2019-05-30 21:21:34,433 [salt.state       :1813][INFO    ][15484] Executing state module.run for [maas.wait_for_machine_status]
2019-05-30 21:21:34,434 [salt.utils.decorators:613 ][WARNING ][15484] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-30 21:21:37,483 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2246.97467995s left)
2019-05-30 21:21:41,408 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212141392300
2019-05-30 21:21:41,439 [salt.minion      :1432][INFO    ][15519] Starting a new job with PID 15519
2019-05-30 21:21:41,466 [salt.minion      :1711][INFO    ][15519] Returning information for job: 20190530212141392300
2019-05-30 21:22:10,256 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.20160198s left)
2019-05-30 21:22:11,515 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212211455644
2019-05-30 21:22:11,544 [salt.minion      :1432][INFO    ][15551] Starting a new job with PID 15551
2019-05-30 21:22:11,575 [salt.minion      :1711][INFO    ][15551] Returning information for job: 20190530212211455644
2019-05-30 21:22:41,603 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212241595717
2019-05-30 21:22:41,625 [salt.minion      :1432][INFO    ][15597] Starting a new job with PID 15597
2019-05-30 21:22:41,656 [salt.minion      :1711][INFO    ][15597] Returning information for job: 20190530212241595717
2019-05-30 21:22:43,042 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.41610885s left)
2019-05-30 21:23:11,688 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212311674347
2019-05-30 21:23:11,717 [salt.minion      :1432][INFO    ][15628] Starting a new job with PID 15628
2019-05-30 21:23:11,756 [salt.minion      :1711][INFO    ][15628] Returning information for job: 20190530212311674347
2019-05-30 21:23:15,877 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.58090401s left)
2019-05-30 21:23:41,803 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212341787009
2019-05-30 21:23:41,831 [salt.minion      :1432][INFO    ][15670] Starting a new job with PID 15670
2019-05-30 21:23:41,860 [salt.minion      :1711][INFO    ][15670] Returning information for job: 20190530212341787009
2019-05-30 21:23:48,666 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.79190683s left)
2019-05-30 21:24:11,888 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212411875644
2019-05-30 21:24:11,910 [salt.minion      :1432][INFO    ][15704] Starting a new job with PID 15704
2019-05-30 21:24:11,939 [salt.minion      :1711][INFO    ][15704] Returning information for job: 20190530212411875644
2019-05-30 21:24:21,689 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.76864481s left)
2019-05-30 21:24:41,983 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212441963615
2019-05-30 21:24:42,012 [salt.minion      :1432][INFO    ][15750] Starting a new job with PID 15750
2019-05-30 21:24:42,042 [salt.minion      :1711][INFO    ][15750] Returning information for job: 20190530212441963615
2019-05-30 21:24:54,405 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2050.05289292s left)
2019-05-30 21:25:12,092 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212512077622
2019-05-30 21:25:12,123 [salt.minion      :1432][INFO    ][15783] Starting a new job with PID 15783
2019-05-30 21:25:12,152 [salt.minion      :1711][INFO    ][15783] Returning information for job: 20190530212512077622
2019-05-30 21:25:27,151 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.30751681s left)
2019-05-30 21:25:42,200 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212542186840
2019-05-30 21:25:42,228 [salt.minion      :1432][INFO    ][15826] Starting a new job with PID 15826
2019-05-30 21:25:42,261 [salt.minion      :1711][INFO    ][15826] Returning information for job: 20190530212542186840
2019-05-30 21:26:00,018 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.43961287s left)
2019-05-30 21:26:12,320 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212612305466
2019-05-30 21:26:12,351 [salt.minion      :1432][INFO    ][15882] Starting a new job with PID 15882
2019-05-30 21:26:12,379 [salt.minion      :1711][INFO    ][15882] Returning information for job: 20190530212612305466
2019-05-30 21:26:32,877 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.58144283s left)
2019-05-30 21:26:42,444 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212642427212
2019-05-30 21:26:42,470 [salt.minion      :1432][INFO    ][15954] Starting a new job with PID 15954
2019-05-30 21:26:42,503 [salt.minion      :1711][INFO    ][15954] Returning information for job: 20190530212642427212
2019-05-30 21:27:05,837 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.62068486s left)
2019-05-30 21:27:12,583 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212712566481
2019-05-30 21:27:12,612 [salt.minion      :1432][INFO    ][16060] Starting a new job with PID 16060
2019-05-30 21:27:12,652 [salt.minion      :1711][INFO    ][16060] Returning information for job: 20190530212712566481
2019-05-30 21:27:38,937 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.52100492s left)
2019-05-30 21:27:42,728 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212742712773
2019-05-30 21:27:42,761 [salt.minion      :1432][INFO    ][16148] Starting a new job with PID 16148
2019-05-30 21:27:42,787 [salt.minion      :1711][INFO    ][16148] Returning information for job: 20190530212742712773
2019-05-30 21:28:11,900 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.55758882s left)
2019-05-30 21:28:12,876 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212812865669
2019-05-30 21:28:12,906 [salt.minion      :1432][INFO    ][16240] Starting a new job with PID 16240
2019-05-30 21:28:12,942 [salt.minion      :1711][INFO    ][16240] Returning information for job: 20190530212812865669
2019-05-30 21:28:43,026 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212843013377
2019-05-30 21:28:43,052 [salt.minion      :1432][INFO    ][16289] Starting a new job with PID 16289
2019-05-30 21:28:43,086 [salt.minion      :1711][INFO    ][16289] Returning information for job: 20190530212843013377
2019-05-30 21:28:44,770 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.68780589s left)
2019-05-30 21:29:13,192 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212913173043
2019-05-30 21:29:13,222 [salt.minion      :1432][INFO    ][16349] Starting a new job with PID 16349
2019-05-30 21:29:13,252 [salt.minion      :1711][INFO    ][16349] Returning information for job: 20190530212913173043
2019-05-30 21:29:17,673 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.78536892s left)
2019-05-30 21:29:43,330 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530212943318948
2019-05-30 21:29:43,362 [salt.minion      :1432][INFO    ][16402] Starting a new job with PID 16402
2019-05-30 21:29:43,392 [salt.minion      :1711][INFO    ][16402] Returning information for job: 20190530212943318948
2019-05-30 21:29:51,164 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.29386902s left)
2019-05-30 21:30:13,533 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530213013517345
2019-05-30 21:30:13,560 [salt.minion      :1432][INFO    ][16540] Starting a new job with PID 16540
2019-05-30 21:30:13,589 [salt.minion      :1711][INFO    ][16540] Returning information for job: 20190530213013517345
2019-05-30 21:30:24,121 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.33723593s left)
2019-05-30 21:30:43,700 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530213043688022
2019-05-30 21:30:43,723 [salt.minion      :1432][INFO    ][16591] Starting a new job with PID 16591
2019-05-30 21:30:43,752 [salt.minion      :1711][INFO    ][16591] Returning information for job: 20190530213043688022
2019-05-30 21:30:57,106 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1687.35199499s left)
2019-05-30 21:31:13,879 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530213113867460
2019-05-30 21:31:13,909 [salt.minion      :1432][INFO    ][16635] Starting a new job with PID 16635
2019-05-30 21:31:13,943 [salt.minion      :1711][INFO    ][16635] Returning information for job: 20190530213113867460
2019-05-30 21:31:30,054 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.40403986s left)
2019-05-30 21:31:44,070 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530213144055620
2019-05-30 21:31:44,098 [salt.minion      :1432][INFO    ][16679] Starting a new job with PID 16679
2019-05-30 21:31:44,130 [salt.minion      :1711][INFO    ][16679] Returning information for job: 20190530213144055620
2019-05-30 21:32:03,007 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.45060492s left)
2019-05-30 21:32:14,260 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530213214247460
2019-05-30 21:32:14,288 [salt.minion      :1432][INFO    ][16712] Starting a new job with PID 16712
2019-05-30 21:32:14,320 [salt.minion      :1711][INFO    ][16712] Returning information for job: 20190530213214247460
2019-05-30 21:32:35,857 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.6008358s left)
2019-05-30 21:32:44,466 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530213244451144
2019-05-30 21:32:44,495 [salt.minion      :1432][INFO    ][16759] Starting a new job with PID 16759
2019-05-30 21:32:44,525 [salt.minion      :1711][INFO    ][16759] Returning information for job: 20190530213244451144
2019-05-30 21:33:08,803 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.65478897s left)
2019-05-30 21:33:14,636 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530213314628496
2019-05-30 21:33:14,662 [salt.minion      :1432][INFO    ][16794] Starting a new job with PID 16794
2019-05-30 21:33:14,699 [salt.minion      :1711][INFO    ][16794] Returning information for job: 20190530213314628496
2019-05-30 21:33:41,636 [salt.loaded.ext.module.maas:1023][INFO    ][15484] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.82252502s left)
2019-05-30 21:33:44,851 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command saltutil.find_job with jid 20190530213344833488
2019-05-30 21:33:44,880 [salt.minion      :1432][INFO    ][16837] Starting a new job with PID 16837
2019-05-30 21:33:44,906 [salt.minion      :1711][INFO    ][16837] Returning information for job: 20190530213344833488
2019-05-30 21:34:14,710 [salt.state       :300 ][INFO    ][15484] {'ret': True}
2019-05-30 21:34:14,711 [salt.state       :1951][INFO    ][15484] Completed state [maas.wait_for_machine_status] at time 21:34:14.710859 duration_in_ms=760278.124
2019-05-30 21:34:14,719 [salt.minion      :1711][INFO    ][15484] Returning information for job: 20190530212126364666
2019-05-30 22:10:42,612 [salt.utils.schedule:1377][INFO    ][2732] Running scheduled job: __mine_interval
2019-05-30 22:54:41,680 [salt.minion      :1308][INFO    ][2732] User sudo_ubuntu Executing command cp.push_dir with jid 20190530225441665268
2019-05-30 22:54:41,707 [salt.minion      :1432][INFO    ][22710] Starting a new job with PID 22710
