2019-06-04 05:04:50,571 [salt.utils.decorators:613 ][WARNING ][2076] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 05:04:51,324 [salt.utils.decorators:613 ][WARNING ][2076] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 05:04:54,202 [salt.loaded.int.states.file:2298][WARNING ][2472] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-04 05:05:15,156 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2927] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-04 05:05:15,180 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2927] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-04 05:05:15,220 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-06-04 05:05:16,248 [salt.cli.daemons :293 ][INFO    ][3035] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-06-04 05:05:16,383 [salt.cli.daemons :82  ][INFO    ][3035] Starting up the Salt Minion
2019-06-04 05:05:16,384 [salt.utils.event :1017][INFO    ][3035] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-06-04 05:05:17,513 [salt.minion      :976 ][INFO    ][3035] Creating minion process manager
2019-06-04 05:05:19,487 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3035] Executing command ['date', '+%z'] in directory '/root'
2019-06-04 05:05:19,509 [salt.utils.schedule:568 ][INFO    ][3035] Updating job settings for scheduled job: __mine_interval
2019-06-04 05:05:19,510 [salt.minion      :1108][INFO    ][3035] Added mine.update to scheduler
2019-06-04 05:05:19,518 [salt.minion      :1975][INFO    ][3035] Minion is starting as user 'root'
2019-06-04 05:05:19,532 [salt.minion      :2336][INFO    ][3035] Minion is ready to receive requests!
2019-06-04 05:05:24,351 [salt.state       :2022][WARNING ][2936] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-04 05:05:27,396 [salt.utils.decorators:613 ][WARNING ][2936] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 05:05:28,279 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604050528265044
2019-06-04 05:05:28,295 [salt.minion      :1432][INFO    ][3449] Starting a new job with PID 3449
2019-06-04 05:05:28,329 [salt.minion      :1711][INFO    ][3449] Returning information for job: 20190604050528265044
2019-06-04 05:05:58,453 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604050558437647
2019-06-04 05:05:58,475 [salt.minion      :1432][INFO    ][3876] Starting a new job with PID 3876
2019-06-04 05:05:58,503 [salt.minion      :1711][INFO    ][3876] Returning information for job: 20190604050558437647
2019-06-04 05:06:28,648 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604050628632413
2019-06-04 05:06:28,675 [salt.minion      :1432][INFO    ][4093] Starting a new job with PID 4093
2019-06-04 05:06:28,697 [salt.minion      :1711][INFO    ][4093] Returning information for job: 20190604050628632413
2019-06-04 05:06:58,769 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604050658758125
2019-06-04 05:06:58,788 [salt.minion      :1432][INFO    ][4328] Starting a new job with PID 4328
2019-06-04 05:06:58,812 [salt.minion      :1711][INFO    ][4328] Returning information for job: 20190604050658758125
2019-06-04 05:07:28,981 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604050728972844
2019-06-04 05:07:28,997 [salt.minion      :1432][INFO    ][4615] Starting a new job with PID 4615
2019-06-04 05:07:29,017 [salt.minion      :1711][INFO    ][4615] Returning information for job: 20190604050728972844
2019-06-04 05:07:59,192 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604050759182388
2019-06-04 05:07:59,216 [salt.minion      :1432][INFO    ][4895] Starting a new job with PID 4895
2019-06-04 05:07:59,239 [salt.minion      :1711][INFO    ][4895] Returning information for job: 20190604050759182388
2019-06-04 05:08:29,290 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604050829276614
2019-06-04 05:08:29,315 [salt.minion      :1432][INFO    ][5187] Starting a new job with PID 5187
2019-06-04 05:08:29,336 [salt.minion      :1711][INFO    ][5187] Returning information for job: 20190604050829276614
2019-06-04 05:08:59,392 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604050859378520
2019-06-04 05:08:59,412 [salt.minion      :1432][INFO    ][5474] Starting a new job with PID 5474
2019-06-04 05:08:59,434 [salt.minion      :1711][INFO    ][5474] Returning information for job: 20190604050859378520
2019-06-04 05:09:29,579 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604050929569417
2019-06-04 05:09:29,600 [salt.minion      :1432][INFO    ][5760] Starting a new job with PID 5760
2019-06-04 05:09:29,628 [salt.minion      :1711][INFO    ][5760] Returning information for job: 20190604050929569417
2019-06-04 05:09:59,705 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604050959691284
2019-06-04 05:09:59,733 [salt.minion      :1432][INFO    ][6034] Starting a new job with PID 6034
2019-06-04 05:09:59,756 [salt.minion      :1711][INFO    ][6034] Returning information for job: 20190604050959691284
2019-06-04 05:10:29,809 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051029802098
2019-06-04 05:10:29,826 [salt.minion      :1432][INFO    ][6317] Starting a new job with PID 6317
2019-06-04 05:10:29,848 [salt.minion      :1711][INFO    ][6317] Returning information for job: 20190604051029802098
2019-06-04 05:11:00,009 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051059998842
2019-06-04 05:11:00,032 [salt.minion      :1432][INFO    ][6605] Starting a new job with PID 6605
2019-06-04 05:11:00,057 [salt.minion      :1711][INFO    ][6605] Returning information for job: 20190604051059998842
2019-06-04 05:11:30,128 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051130118384
2019-06-04 05:11:30,145 [salt.minion      :1432][INFO    ][6882] Starting a new job with PID 6882
2019-06-04 05:11:30,167 [salt.minion      :1711][INFO    ][6882] Returning information for job: 20190604051130118384
2019-06-04 05:12:00,328 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051200313688
2019-06-04 05:12:00,349 [salt.minion      :1432][INFO    ][7179] Starting a new job with PID 7179
2019-06-04 05:12:00,372 [salt.minion      :1711][INFO    ][7179] Returning information for job: 20190604051200313688
2019-06-04 05:12:30,524 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051230512664
2019-06-04 05:12:30,548 [salt.minion      :1432][INFO    ][7473] Starting a new job with PID 7473
2019-06-04 05:12:30,572 [salt.minion      :1711][INFO    ][7473] Returning information for job: 20190604051230512664
2019-06-04 05:13:00,628 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051300615429
2019-06-04 05:13:00,646 [salt.minion      :1432][INFO    ][7763] Starting a new job with PID 7763
2019-06-04 05:13:00,670 [salt.minion      :1711][INFO    ][7763] Returning information for job: 20190604051300615429
2019-06-04 05:13:30,731 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051330723191
2019-06-04 05:13:30,752 [salt.minion      :1432][INFO    ][8055] Starting a new job with PID 8055
2019-06-04 05:13:30,773 [salt.minion      :1711][INFO    ][8055] Returning information for job: 20190604051330723191
2019-06-04 05:14:00,748 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051400739154
2019-06-04 05:14:00,763 [salt.minion      :1432][INFO    ][8339] Starting a new job with PID 8339
2019-06-04 05:14:00,781 [salt.minion      :1711][INFO    ][8339] Returning information for job: 20190604051400739154
2019-06-04 05:14:30,849 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051430838315
2019-06-04 05:14:30,880 [salt.minion      :1432][INFO    ][8569] Starting a new job with PID 8569
2019-06-04 05:14:30,906 [salt.minion      :1711][INFO    ][8569] Returning information for job: 20190604051430838315
2019-06-04 05:15:00,943 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051500934113
2019-06-04 05:15:00,960 [salt.minion      :1432][INFO    ][8829] Starting a new job with PID 8829
2019-06-04 05:15:00,994 [salt.minion      :1711][INFO    ][8829] Returning information for job: 20190604051500934113
2019-06-04 05:15:31,065 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051531053132
2019-06-04 05:15:31,091 [salt.minion      :1432][INFO    ][9134] Starting a new job with PID 9134
2019-06-04 05:15:31,128 [salt.minion      :1711][INFO    ][9134] Returning information for job: 20190604051531053132
2019-06-04 05:16:01,169 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051601156680
2019-06-04 05:16:01,194 [salt.minion      :1432][INFO    ][9453] Starting a new job with PID 9453
2019-06-04 05:16:01,216 [salt.minion      :1711][INFO    ][9453] Returning information for job: 20190604051601156680
2019-06-04 05:16:31,351 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051631341519
2019-06-04 05:16:31,376 [salt.minion      :1432][INFO    ][9767] Starting a new job with PID 9767
2019-06-04 05:16:31,398 [salt.minion      :1711][INFO    ][9767] Returning information for job: 20190604051631341519
2019-06-04 05:17:01,447 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051701434725
2019-06-04 05:17:01,468 [salt.minion      :1432][INFO    ][10049] Starting a new job with PID 10049
2019-06-04 05:17:01,491 [salt.minion      :1711][INFO    ][10049] Returning information for job: 20190604051701434725
2019-06-04 05:17:31,651 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051731642049
2019-06-04 05:17:31,692 [salt.minion      :1432][INFO    ][10346] Starting a new job with PID 10346
2019-06-04 05:17:31,817 [salt.minion      :1711][INFO    ][10346] Returning information for job: 20190604051731642049
2019-06-04 05:18:01,749 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051801738523
2019-06-04 05:18:01,781 [salt.minion      :1432][INFO    ][10605] Starting a new job with PID 10605
2019-06-04 05:18:01,908 [salt.minion      :1711][INFO    ][10605] Returning information for job: 20190604051801738523
2019-06-04 05:18:31,832 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051831821415
2019-06-04 05:18:31,859 [salt.minion      :1432][INFO    ][10856] Starting a new job with PID 10856
2019-06-04 05:18:31,902 [salt.minion      :1711][INFO    ][10856] Returning information for job: 20190604051831821415
2019-06-04 05:19:01,984 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051901965295
2019-06-04 05:19:02,004 [salt.minion      :1432][INFO    ][11032] Starting a new job with PID 11032
2019-06-04 05:19:02,034 [salt.minion      :1711][INFO    ][11032] Returning information for job: 20190604051901965295
2019-06-04 05:19:32,156 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604051932142931
2019-06-04 05:19:32,183 [salt.minion      :1432][INFO    ][11317] Starting a new job with PID 11317
2019-06-04 05:19:32,207 [salt.minion      :1711][INFO    ][11317] Returning information for job: 20190604051932142931
2019-06-04 05:20:02,264 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052002251873
2019-06-04 05:20:02,304 [salt.minion      :1432][INFO    ][11587] Starting a new job with PID 11587
2019-06-04 05:20:02,327 [salt.minion      :1711][INFO    ][11587] Returning information for job: 20190604052002251873
2019-06-04 05:20:32,362 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052032349284
2019-06-04 05:20:32,387 [salt.minion      :1432][INFO    ][11880] Starting a new job with PID 11880
2019-06-04 05:20:32,409 [salt.minion      :1711][INFO    ][11880] Returning information for job: 20190604052032349284
2019-06-04 05:20:35,014 [salt.utils.decorators:613 ][WARNING ][2936] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 05:21:02,572 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052102557255
2019-06-04 05:21:02,596 [salt.minion      :1432][INFO    ][12155] Starting a new job with PID 12155
2019-06-04 05:21:02,622 [salt.minion      :1711][INFO    ][12155] Returning information for job: 20190604052102557255
2019-06-04 05:21:32,756 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052132746090
2019-06-04 05:21:32,781 [salt.minion      :1432][INFO    ][12450] Starting a new job with PID 12450
2019-06-04 05:21:32,807 [salt.minion      :1711][INFO    ][12450] Returning information for job: 20190604052132746090
2019-06-04 05:22:02,856 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052202840711
2019-06-04 05:22:02,881 [salt.minion      :1432][INFO    ][12724] Starting a new job with PID 12724
2019-06-04 05:22:02,915 [salt.minion      :1711][INFO    ][12724] Returning information for job: 20190604052202840711
2019-06-04 05:22:33,085 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052233067135
2019-06-04 05:22:33,113 [salt.minion      :1432][INFO    ][13033] Starting a new job with PID 13033
2019-06-04 05:22:33,148 [salt.minion      :1711][INFO    ][13033] Returning information for job: 20190604052233067135
2019-06-04 05:23:03,147 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052303132842
2019-06-04 05:23:03,169 [salt.minion      :1432][INFO    ][13308] Starting a new job with PID 13308
2019-06-04 05:23:03,199 [salt.minion      :1711][INFO    ][13308] Returning information for job: 20190604052303132842
2019-06-04 05:23:33,308 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052333298700
2019-06-04 05:23:33,331 [salt.minion      :1432][INFO    ][13582] Starting a new job with PID 13582
2019-06-04 05:23:33,356 [salt.minion      :1711][INFO    ][13582] Returning information for job: 20190604052333298700
2019-06-04 05:24:03,430 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052403411437
2019-06-04 05:24:03,452 [salt.minion      :1432][INFO    ][13856] Starting a new job with PID 13856
2019-06-04 05:24:03,476 [salt.minion      :1711][INFO    ][13856] Returning information for job: 20190604052403411437
2019-06-04 05:24:33,529 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052433518698
2019-06-04 05:24:33,553 [salt.minion      :1432][INFO    ][14078] Starting a new job with PID 14078
2019-06-04 05:24:33,584 [salt.minion      :1711][INFO    ][14078] Returning information for job: 20190604052433518698
2019-06-04 05:24:45,211 [salt.utils.decorators:613 ][WARNING ][2936] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 05:25:03,584 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052503569075
2019-06-04 05:25:03,628 [salt.minion      :1432][INFO    ][14153] Starting a new job with PID 14153
2019-06-04 05:25:03,648 [salt.minion      :1711][INFO    ][14153] Returning information for job: 20190604052503569075
2019-06-04 05:25:33,713 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052533703045
2019-06-04 05:25:33,738 [salt.minion      :1432][INFO    ][14223] Starting a new job with PID 14223
2019-06-04 05:25:33,779 [salt.minion      :1711][INFO    ][14223] Returning information for job: 20190604052533703045
2019-06-04 05:26:03,804 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052603793515
2019-06-04 05:26:03,826 [salt.minion      :1432][INFO    ][14275] Starting a new job with PID 14275
2019-06-04 05:26:03,857 [salt.minion      :1711][INFO    ][14275] Returning information for job: 20190604052603793515
2019-06-04 05:26:33,944 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052633904118
2019-06-04 05:26:33,980 [salt.minion      :1432][INFO    ][14328] Starting a new job with PID 14328
2019-06-04 05:26:34,015 [salt.minion      :1711][INFO    ][14328] Returning information for job: 20190604052633904118
2019-06-04 05:27:04,072 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052704011319
2019-06-04 05:27:04,101 [salt.minion      :1432][INFO    ][14362] Starting a new job with PID 14362
2019-06-04 05:27:04,123 [salt.minion      :1711][INFO    ][14362] Returning information for job: 20190604052704011319
2019-06-04 05:27:34,246 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052734232237
2019-06-04 05:27:34,273 [salt.minion      :1432][INFO    ][14441] Starting a new job with PID 14441
2019-06-04 05:27:34,300 [salt.minion      :1711][INFO    ][14441] Returning information for job: 20190604052734232237
2019-06-04 05:27:41,025 [salt.utils.decorators:613 ][WARNING ][2936] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 05:27:43,526 [salt.loaded.ext.module.maasng:1008][WARNING ][2936] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-04 05:27:43,527 [salt.loaded.ext.module.maasng:1011][WARNING ][2936] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-04 05:27:44,377 [salt.loaded.ext.module.maasng:1235][WARNING ][2936] Ignoring parameter vlan:0
2019-06-04 05:27:45,370 [salt.utils.decorators:613 ][WARNING ][2936] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 05:27:46,882 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604052746811763
2019-06-04 05:27:46,907 [salt.minion      :1432][INFO    ][14678] Starting a new job with PID 14678
2019-06-04 05:27:54,515 [salt.state       :915 ][INFO    ][14678] Loading fresh modules for state activity
2019-06-04 05:27:54,654 [salt.fileclient  :1219][INFO    ][14678] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-06-04 05:27:54,772 [salt.state       :1780][INFO    ][14678] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:27:54.772359
2019-06-04 05:27:54,773 [salt.state       :1813][INFO    ][14678] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-04 05:27:54,778 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14678] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-04 05:27:56,954 [salt.state       :300 ][INFO    ][14678] {'pid': 14726, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-04 05:27:56,955 [salt.state       :1951][INFO    ][14678] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:27:56.955768 duration_in_ms=2183.409
2019-06-04 05:27:56,960 [salt.state       :1780][INFO    ][14678] Running state [maas.process_machines] at time 05:27:56.960720
2019-06-04 05:27:56,961 [salt.state       :1813][INFO    ][14678] Executing state module.run for [maas.process_machines]
2019-06-04 05:27:56,963 [salt.utils.decorators:613 ][WARNING ][14678] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 05:27:57,062 [salt.loaded.ext.module.maas:412 ][WARNING ][14678] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-04 05:27:57,063 [salt.loaded.ext.module.maas:92  ][INFO    ][14678] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-04 05:27:58,651 [salt.loaded.ext.module.maas:412 ][WARNING ][14678] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-04 05:27:58,651 [salt.loaded.ext.module.maas:92  ][INFO    ][14678] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-04 05:28:00,115 [salt.loaded.ext.module.maas:412 ][WARNING ][14678] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-04 05:28:00,115 [salt.loaded.ext.module.maas:92  ][INFO    ][14678] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-04 05:28:01,643 [salt.loaded.ext.module.maas:412 ][WARNING ][14678] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-04 05:28:01,644 [salt.loaded.ext.module.maas:92  ][INFO    ][14678] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-04 05:28:01,916 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052801901569
2019-06-04 05:28:01,941 [salt.minion      :1432][INFO    ][14924] Starting a new job with PID 14924
2019-06-04 05:28:01,967 [salt.minion      :1711][INFO    ][14924] Returning information for job: 20190604052801901569
2019-06-04 05:28:03,107 [salt.loaded.ext.module.maas:412 ][WARNING ][14678] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-04 05:28:03,107 [salt.loaded.ext.module.maas:92  ][INFO    ][14678] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-04 05:28:04,595 [salt.state       :300 ][INFO    ][14678] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-04 05:28:04,596 [salt.state       :1951][INFO    ][14678] Completed state [maas.process_machines] at time 05:28:04.595915 duration_in_ms=7635.193
2019-06-04 05:28:04,602 [salt.minion      :1711][INFO    ][14678] Returning information for job: 20190604052746811763
2019-06-04 05:28:44,164 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604052844134469
2019-06-04 05:28:44,191 [salt.minion      :1432][INFO    ][15082] Starting a new job with PID 15082
2019-06-04 05:28:50,407 [salt.state       :915 ][INFO    ][15082] Loading fresh modules for state activity
2019-06-04 05:28:50,506 [salt.fileclient  :1219][INFO    ][15082] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-04 05:28:50,586 [salt.state       :1780][INFO    ][15082] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:28:50.586881
2019-06-04 05:28:50,587 [salt.state       :1813][INFO    ][15082] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-04 05:28:50,588 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15082] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-04 05:28:52,390 [salt.state       :300 ][INFO    ][15082] {'pid': 15089, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-04 05:28:52,391 [salt.state       :1951][INFO    ][15082] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:28:52.391429 duration_in_ms=1804.548
2019-06-04 05:28:52,395 [salt.state       :1780][INFO    ][15082] Running state [maas.wait_for_machine_status] at time 05:28:52.395694
2019-06-04 05:28:52,396 [salt.state       :1813][INFO    ][15082] Executing state module.run for [maas.wait_for_machine_status]
2019-06-04 05:28:52,396 [salt.utils.decorators:613 ][WARNING ][15082] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 05:28:53,289 [salt.loaded.ext.module.maas:1023][INFO    ][15082] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.11471486s left)
2019-06-04 05:28:59,261 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052859247500
2019-06-04 05:28:59,291 [salt.minion      :1432][INFO    ][15104] Starting a new job with PID 15104
2019-06-04 05:28:59,314 [salt.minion      :1711][INFO    ][15104] Returning information for job: 20190604052859247500
2019-06-04 05:29:24,163 [salt.loaded.ext.module.maas:1023][INFO    ][15082] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.24050593s left)
2019-06-04 05:29:29,369 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052929346318
2019-06-04 05:29:29,387 [salt.minion      :1432][INFO    ][15144] Starting a new job with PID 15144
2019-06-04 05:29:29,415 [salt.minion      :1711][INFO    ][15144] Returning information for job: 20190604052929346318
2019-06-04 05:29:55,006 [salt.loaded.ext.module.maas:1023][INFO    ][15082] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.39688802s left)
2019-06-04 05:29:59,461 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604052959394679
2019-06-04 05:29:59,485 [salt.minion      :1432][INFO    ][15181] Starting a new job with PID 15181
2019-06-04 05:29:59,511 [salt.minion      :1711][INFO    ][15181] Returning information for job: 20190604052959394679
2019-06-04 05:30:25,939 [salt.loaded.ext.module.maas:1023][INFO    ][15082] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.46403098s left)
2019-06-04 05:30:29,611 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053029575178
2019-06-04 05:30:29,628 [salt.minion      :1432][INFO    ][15249] Starting a new job with PID 15249
2019-06-04 05:30:29,653 [salt.minion      :1711][INFO    ][15249] Returning information for job: 20190604053029575178
2019-06-04 05:30:57,107 [salt.loaded.ext.module.maas:1023][INFO    ][15082] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.29593992s left)
2019-06-04 05:30:59,699 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053059687315
2019-06-04 05:30:59,728 [salt.minion      :1432][INFO    ][15344] Starting a new job with PID 15344
2019-06-04 05:30:59,754 [salt.minion      :1711][INFO    ][15344] Returning information for job: 20190604053059687315
2019-06-04 05:31:29,060 [salt.loaded.ext.module.maas:1023][INFO    ][15082] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.34374785s left)
2019-06-04 05:31:29,849 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053129823972
2019-06-04 05:31:29,875 [salt.minion      :1432][INFO    ][15549] Starting a new job with PID 15549
2019-06-04 05:31:29,900 [salt.minion      :1711][INFO    ][15549] Returning information for job: 20190604053129823972
2019-06-04 05:31:59,957 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053159939340
2019-06-04 05:31:59,974 [salt.minion      :1432][INFO    ][15674] Starting a new job with PID 15674
2019-06-04 05:32:00,000 [salt.minion      :1711][INFO    ][15674] Returning information for job: 20190604053159939340
2019-06-04 05:32:00,459 [salt.loaded.ext.module.maas:1023][INFO    ][15082] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1311.94475985s left)
2019-06-04 05:32:30,088 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053230066622
2019-06-04 05:32:30,117 [salt.minion      :1432][INFO    ][15863] Starting a new job with PID 15863
2019-06-04 05:32:30,142 [salt.minion      :1711][INFO    ][15863] Returning information for job: 20190604053230066622
2019-06-04 05:32:32,123 [salt.loaded.ext.module.maas:1023][INFO    ][15082] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.28024483s left)
2019-06-04 05:33:00,269 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053300237268
2019-06-04 05:33:00,299 [salt.minion      :1432][INFO    ][16006] Starting a new job with PID 16006
2019-06-04 05:33:00,322 [salt.minion      :1711][INFO    ][16006] Returning information for job: 20190604053300237268
2019-06-04 05:33:04,099 [salt.loaded.ext.module.maas:1023][INFO    ][15082] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.30438995s left)
2019-06-04 05:33:30,442 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053330429779
2019-06-04 05:33:30,465 [salt.minion      :1432][INFO    ][16422] Starting a new job with PID 16422
2019-06-04 05:33:30,493 [salt.minion      :1711][INFO    ][16422] Returning information for job: 20190604053330429779
2019-06-04 05:33:36,476 [salt.loaded.ext.module.maas:1023][INFO    ][15082] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1215.92713284s left)
2019-06-04 05:34:00,587 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053400560899
2019-06-04 05:34:00,618 [salt.minion      :1432][INFO    ][16596] Starting a new job with PID 16596
2019-06-04 05:34:00,642 [salt.minion      :1711][INFO    ][16596] Returning information for job: 20190604053400560899
2019-06-04 05:34:08,775 [salt.loaded.ext.module.maas:1023][INFO    ][15082] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1183.62842798s left)
2019-06-04 05:34:30,776 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053430737421
2019-06-04 05:34:30,801 [salt.minion      :1432][INFO    ][16714] Starting a new job with PID 16714
2019-06-04 05:34:30,825 [salt.minion      :1711][INFO    ][16714] Returning information for job: 20190604053430737421
2019-06-04 05:34:41,651 [salt.state       :300 ][INFO    ][15082] {'ret': True}
2019-06-04 05:34:41,652 [salt.state       :1951][INFO    ][15082] Completed state [maas.wait_for_machine_status] at time 05:34:41.652173 duration_in_ms=349256.478
2019-06-04 05:34:41,657 [salt.minion      :1711][INFO    ][15082] Returning information for job: 20190604052844134469
2019-06-04 05:34:42,315 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604053442306419
2019-06-04 05:34:42,341 [salt.minion      :1432][INFO    ][16755] Starting a new job with PID 16755
2019-06-04 05:34:48,931 [salt.state       :915 ][INFO    ][16755] Loading fresh modules for state activity
2019-06-04 05:34:49,000 [salt.fileclient  :1219][INFO    ][16755] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-04 05:34:49,115 [salt.state       :1780][INFO    ][16755] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:49.115135
2019-06-04 05:34:49,115 [salt.state       :1813][INFO    ][16755] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-04 05:34:49,121 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16755] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-04 05:34:50,874 [salt.state       :300 ][INFO    ][16755] {'pid': 16773, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-04 05:34:50,875 [salt.state       :1951][INFO    ][16755] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:50.875247 duration_in_ms=1760.113
2019-06-04 05:34:50,879 [salt.state       :1780][INFO    ][16755] Running state [maas_machines_storage_cmp002_lvm] at time 05:34:50.879466
2019-06-04 05:34:50,880 [salt.state       :1813][INFO    ][16755] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-04 05:34:52,055 [salt.loaded.ext.module.maasng:610 ][INFO    ][16755] nem7xf
2019-06-04 05:34:52,055 [salt.loaded.ext.module.maasng:626 ][INFO    ][16755] sda
2019-06-04 05:34:52,641 [salt.loaded.ext.module.maasng:361 ][INFO    ][16755] nem7xf
2019-06-04 05:34:52,735 [salt.loaded.ext.module.maasng:367 ][INFO    ][16755] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'd29b13d9-4161-478c-8cc0-ecaf25a84510', u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nem7xf', u'filesystem': {u'mount_options': None, u'uuid': u'd909b8d6-2ec9-4a6d-9bd3-7af400d80fe7', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nem7xf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/blockdevices/1/'}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'18ff1d84-9f5a-4375-a6f1-2df958b09e06', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nem7xf', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'2bae161c-039f-498c-8051-34fcc80736e8', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 800097042432, u'used_size': 800097042432, u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/blockdevices/3/'}]
2019-06-04 05:34:52,735 [salt.loaded.ext.module.maasng:632 ][INFO    ][16755] vgroot
2019-06-04 05:34:52,736 [salt.loaded.ext.module.maasng:635 ][INFO    ][16755] lvroot
2019-06-04 05:34:52,736 [salt.loaded.ext.module.maasng:639 ][INFO    ][16755] 107374182400
2019-06-04 05:34:53,346 [salt.loaded.ext.module.maasng:645 ][INFO    ][16755] {u'hwe_kernel': u'', u'status_action': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], 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'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 21, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'nem7xf', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'92b609f6-558c-4d4d-a00d-f4b9347b7526', u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nem7xf', u'filesystem': {u'mount_options': None, u'uuid': u'ac04d588-229c-4c12-b3eb-62b056c18f71', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nem7xf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'size': 800109715456}, u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'92b609f6-558c-4d4d-a00d-f4b9347b7526', u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nem7xf', u'filesystem': {u'mount_options': None, u'uuid': u'ac04d588-229c-4c12-b3eb-62b056c18f71', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nem7xf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/blockdevices/1/'}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'51787266-7420-41a0-8076-a8c4a726bdef', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nem7xf', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'472d15e0-4d89-43e9-b5ac-a02b831cc6eb', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/blockdevices/11/'}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/nem7xf/', u'disable_ipv4': False, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'nem7xf', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'51787266-7420-41a0-8076-a8c4a726bdef', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nem7xf', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'472d15e0-4d89-43e9-b5ac-a02b831cc6eb', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'size': 107374182400}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], 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'nem7xf', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 21, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'nem7xf', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'nem7xf', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/interfaces/12/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'nem7xf', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/interfaces/16/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'nem7xf', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/interfaces/13/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'nem7xf', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/interfaces/14/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 24, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'nem7xf', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/interfaces/15/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'92b609f6-558c-4d4d-a00d-f4b9347b7526', u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nem7xf', u'filesystem': {u'mount_options': None, u'uuid': u'ac04d588-229c-4c12-b3eb-62b056c18f71', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nem7xf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/nem7xf/blockdevices/1/', u'used_size': 800106479616, u'id': 1, u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-04 05:34:53,348 [salt.state       :300 ][INFO    ][16755] {'new': {'storage_layout': 'lvm'}}
2019-06-04 05:34:53,349 [salt.state       :1951][INFO    ][16755] Completed state [maas_machines_storage_cmp002_lvm] at time 05:34:53.349167 duration_in_ms=2469.699
2019-06-04 05:34:53,350 [salt.state       :1780][INFO    ][16755] Running state [maas_machines_storage_cmp001_lvm] at time 05:34:53.350188
2019-06-04 05:34:53,350 [salt.state       :1813][INFO    ][16755] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-04 05:34:54,465 [salt.loaded.ext.module.maasng:610 ][INFO    ][16755] ns6y3x
2019-06-04 05:34:54,466 [salt.loaded.ext.module.maasng:626 ][INFO    ][16755] sda
2019-06-04 05:34:55,258 [salt.loaded.ext.module.maasng:361 ][INFO    ][16755] ns6y3x
2019-06-04 05:34:55,358 [salt.loaded.ext.module.maasng:367 ][INFO    ][16755] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ns6y3x', u'partition_table_type': u'MBR', u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/blockdevices/2/', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'c305b820-a8c3-4620-b38d-e29d157e026b', u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ns6y3x', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'e55cb493-79b7-4c05-93da-05af5490363e'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'device_id': 2}]}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'id': 4, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'4cc81b8b-91b1-4df6-8f13-bfc5ac3f3e30'}, u'uuid': u'67914eb3-5c83-4418-82a4-0b41067b990c', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ns6y3x', u'partition_table_type': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/blockdevices/4/', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 800097042432, u'type': u'virtual', u'model': None, u'partitions': []}]
2019-06-04 05:34:55,359 [salt.loaded.ext.module.maasng:632 ][INFO    ][16755] vgroot
2019-06-04 05:34:55,360 [salt.loaded.ext.module.maasng:635 ][INFO    ][16755] lvroot
2019-06-04 05:34:55,360 [salt.loaded.ext.module.maasng:639 ][INFO    ][16755] 107374182400
2019-06-04 05:34:55,961 [salt.loaded.ext.module.maasng:645 ][INFO    ][16755] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/ns6y3x/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ns6y3x', 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'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'ns6y3x', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'cbe5d1b9-539a-447c-9b35-7614cc1f381d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ns6y3x', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b3af53ec-2d2c-4c6f-a99a-7c64f85cbd04', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/blockdevices/12/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'uuid': u'540a0ec4-3e1f-4f37-b59f-808e92facc09', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'ns6y3x', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'212b7205-da14-4484-9fd2-f6c963d5f6d5', u'fstype': u'ext4'}, u'block_size': 4096, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'model': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'ns6y3x', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'cbe5d1b9-539a-447c-9b35-7614cc1f381d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ns6y3x', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b3af53ec-2d2c-4c6f-a99a-7c64f85cbd04', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ns6y3x', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hasksm', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hasksm', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hasksm', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'system_id': u'ns6y3x', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/interfaces/6/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hasksm', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hasksm', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hasksm', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'system_id': u'ns6y3x', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 19, u'system_id': u'ns6y3x', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/interfaces/19/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 20, u'system_id': u'ns6y3x', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/interfaces/20/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hasksm', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 28}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hasksm', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hasksm', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.44'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 17, u'system_id': u'ns6y3x', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/interfaces/17/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 29}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 18, u'system_id': u'ns6y3x', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 30}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 21, u'system_id': u'ns6y3x', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/interfaces/21/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/blockdevices/12/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'uuid': u'540a0ec4-3e1f-4f37-b59f-808e92facc09', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'ns6y3x', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'212b7205-da14-4484-9fd2-f6c963d5f6d5', u'fstype': u'ext4'}, u'block_size': 4096, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'model': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'ns6y3x', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'cbe5d1b9-539a-447c-9b35-7614cc1f381d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ns6y3x', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b3af53ec-2d2c-4c6f-a99a-7c64f85cbd04', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ns6y3x/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}}
2019-06-04 05:34:55,964 [salt.state       :300 ][INFO    ][16755] {'new': {'storage_layout': 'lvm'}}
2019-06-04 05:34:55,965 [salt.state       :1951][INFO    ][16755] Completed state [maas_machines_storage_cmp001_lvm] at time 05:34:55.964931 duration_in_ms=2614.742
2019-06-04 05:34:55,969 [salt.minion      :1711][INFO    ][16755] Returning information for job: 20190604053442306419
2019-06-04 05:34:56,530 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604053456517699
2019-06-04 05:34:56,551 [salt.minion      :1432][INFO    ][16810] Starting a new job with PID 16810
2019-06-04 05:34:57,742 [salt.state       :915 ][INFO    ][16810] Loading fresh modules for state activity
2019-06-04 05:34:57,803 [salt.fileclient  :1219][INFO    ][16810] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-04 05:34:57,857 [salt.state       :1780][INFO    ][16810] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:57.857083
2019-06-04 05:34:57,857 [salt.state       :1813][INFO    ][16810] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-04 05:34:57,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16810] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-04 05:34:59,660 [salt.state       :300 ][INFO    ][16810] {'pid': 16820, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-04 05:34:59,661 [salt.state       :1951][INFO    ][16810] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:34:59.661594 duration_in_ms=1804.51
2019-06-04 05:34:59,664 [salt.state       :1780][INFO    ][16810] Running state [maas.deploy_machines] at time 05:34:59.664335
2019-06-04 05:34:59,665 [salt.state       :1813][INFO    ][16810] Executing state module.run for [maas.deploy_machines]
2019-06-04 05:34:59,667 [salt.utils.decorators:613 ][WARNING ][16810] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 05:35:00,234 [salt.loaded.ext.module.maas:684 ][INFO    ][16810] deploymachines hwe_kernel=hwe-16.04 system_id=nem7xf distro_series=xenial
2019-06-04 05:35:02,554 [salt.loaded.ext.module.maas:684 ][INFO    ][16810] deploymachines hwe_kernel=hwe-16.04 system_id=ns6y3x distro_series=xenial
2019-06-04 05:35:05,213 [salt.loaded.ext.module.maas:684 ][INFO    ][16810] deploymachines hwe_kernel=hwe-16.04 system_id=dyqwmk distro_series=xenial
2019-06-04 05:35:07,774 [salt.loaded.ext.module.maas:684 ][INFO    ][16810] deploymachines hwe_kernel=hwe-16.04 system_id=htt6qt distro_series=xenial
2019-06-04 05:35:10,330 [salt.loaded.ext.module.maas:684 ][INFO    ][16810] deploymachines hwe_kernel=hwe-16.04 system_id=fg8fdq distro_series=xenial
2019-06-04 05:35:11,657 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053511638748
2019-06-04 05:35:11,675 [salt.minion      :1432][INFO    ][17077] Starting a new job with PID 17077
2019-06-04 05:35:11,705 [salt.minion      :1711][INFO    ][17077] Returning information for job: 20190604053511638748
2019-06-04 05:35:12,788 [salt.state       :300 ][INFO    ][16810] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-04 05:35:12,789 [salt.state       :1951][INFO    ][16810] Completed state [maas.deploy_machines] at time 05:35:12.789174 duration_in_ms=13124.839
2019-06-04 05:35:12,793 [salt.minion      :1711][INFO    ][16810] Returning information for job: 20190604053456517699
2019-06-04 05:35:13,375 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604053513360551
2019-06-04 05:35:13,401 [salt.minion      :1432][INFO    ][17096] Starting a new job with PID 17096
2019-06-04 05:35:20,696 [salt.state       :915 ][INFO    ][17096] Loading fresh modules for state activity
2019-06-04 05:35:20,757 [salt.fileclient  :1219][INFO    ][17096] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-04 05:35:20,809 [salt.state       :1780][INFO    ][17096] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:20.809326
2019-06-04 05:35:20,809 [salt.state       :1813][INFO    ][17096] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-04 05:35:20,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17096] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-04 05:35:22,578 [salt.state       :300 ][INFO    ][17096] {'pid': 17125, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-04 05:35:22,579 [salt.state       :1951][INFO    ][17096] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:22.579179 duration_in_ms=1769.85
2019-06-04 05:35:22,583 [salt.state       :1780][INFO    ][17096] Running state [maas.wait_for_machine_status] at time 05:35:22.583541
2019-06-04 05:35:22,584 [salt.state       :1813][INFO    ][17096] Executing state module.run for [maas.wait_for_machine_status]
2019-06-04 05:35:22,585 [salt.utils.decorators:613 ][WARNING ][17096] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 05:35:25,499 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.10061193s left)
2019-06-04 05:35:28,451 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053528435271
2019-06-04 05:35:28,480 [salt.minion      :1432][INFO    ][17159] Starting a new job with PID 17159
2019-06-04 05:35:28,505 [salt.minion      :1711][INFO    ][17159] Returning information for job: 20190604053528435271
2019-06-04 05:35:58,469 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.13062787s left)
2019-06-04 05:35:58,630 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053558604571
2019-06-04 05:35:58,659 [salt.minion      :1432][INFO    ][17205] Starting a new job with PID 17205
2019-06-04 05:35:58,684 [salt.minion      :1711][INFO    ][17205] Returning information for job: 20190604053558604571
2019-06-04 05:36:28,736 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053628725214
2019-06-04 05:36:28,760 [salt.minion      :1432][INFO    ][17250] Starting a new job with PID 17250
2019-06-04 05:36:28,786 [salt.minion      :1711][INFO    ][17250] Returning information for job: 20190604053628725214
2019-06-04 05:36:31,572 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.027493s left)
2019-06-04 05:36:58,828 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053658814193
2019-06-04 05:36:58,855 [salt.minion      :1432][INFO    ][17287] Starting a new job with PID 17287
2019-06-04 05:36:58,880 [salt.minion      :1711][INFO    ][17287] Returning information for job: 20190604053658814193
2019-06-04 05:37:04,436 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.16403389s left)
2019-06-04 05:37:28,919 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053728902942
2019-06-04 05:37:28,949 [salt.minion      :1432][INFO    ][17327] Starting a new job with PID 17327
2019-06-04 05:37:28,976 [salt.minion      :1711][INFO    ][17327] Returning information for job: 20190604053728902942
2019-06-04 05:37:37,139 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.46114492s left)
2019-06-04 05:37:59,022 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053759008564
2019-06-04 05:37:59,046 [salt.minion      :1432][INFO    ][17404] Starting a new job with PID 17404
2019-06-04 05:37:59,070 [salt.minion      :1711][INFO    ][17404] Returning information for job: 20190604053759008564
2019-06-04 05:38:10,151 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.44844985s left)
2019-06-04 05:38:29,128 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053829113439
2019-06-04 05:38:29,146 [salt.minion      :1432][INFO    ][17545] Starting a new job with PID 17545
2019-06-04 05:38:29,171 [salt.minion      :1711][INFO    ][17545] Returning information for job: 20190604053829113439
2019-06-04 05:38:43,444 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.15542388s left)
2019-06-04 05:38:59,241 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053859228700
2019-06-04 05:38:59,268 [salt.minion      :1432][INFO    ][17619] Starting a new job with PID 17619
2019-06-04 05:38:59,295 [salt.minion      :1711][INFO    ][17619] Returning information for job: 20190604053859228700
2019-06-04 05:39:16,388 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.21139884s left)
2019-06-04 05:39:29,360 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053929348711
2019-06-04 05:39:29,384 [salt.minion      :1432][INFO    ][17844] Starting a new job with PID 17844
2019-06-04 05:39:29,410 [salt.minion      :1711][INFO    ][17844] Returning information for job: 20190604053929348711
2019-06-04 05:39:49,271 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.32853293s left)
2019-06-04 05:39:59,507 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604053959483546
2019-06-04 05:39:59,538 [salt.minion      :1432][INFO    ][17971] Starting a new job with PID 17971
2019-06-04 05:39:59,572 [salt.minion      :1711][INFO    ][17971] Returning information for job: 20190604053959483546
2019-06-04 05:40:22,115 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.48452401s left)
2019-06-04 05:40:29,647 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054029628129
2019-06-04 05:40:29,672 [salt.minion      :1432][INFO    ][18232] Starting a new job with PID 18232
2019-06-04 05:40:29,697 [salt.minion      :1711][INFO    ][18232] Returning information for job: 20190604054029628129
2019-06-04 05:40:55,059 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.540344s left)
2019-06-04 05:40:59,793 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054059776796
2019-06-04 05:40:59,824 [salt.minion      :1432][INFO    ][18387] Starting a new job with PID 18387
2019-06-04 05:40:59,853 [salt.minion      :1711][INFO    ][18387] Returning information for job: 20190604054059776796
2019-06-04 05:41:28,594 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.00620103s left)
2019-06-04 05:41:29,996 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054129976271
2019-06-04 05:41:30,024 [salt.minion      :1432][INFO    ][18634] Starting a new job with PID 18634
2019-06-04 05:41:30,058 [salt.minion      :1711][INFO    ][18634] Returning information for job: 20190604054129976271
2019-06-04 05:42:00,156 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054200144315
2019-06-04 05:42:00,184 [salt.minion      :1432][INFO    ][18892] Starting a new job with PID 18892
2019-06-04 05:42:00,209 [salt.minion      :1711][INFO    ][18892] Returning information for job: 20190604054200144315
2019-06-04 05:42:01,771 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.828794s left)
2019-06-04 05:42:30,334 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054230321502
2019-06-04 05:42:30,360 [salt.minion      :1432][INFO    ][19140] Starting a new job with PID 19140
2019-06-04 05:42:30,392 [salt.minion      :1711][INFO    ][19140] Returning information for job: 20190604054230321502
2019-06-04 05:42:35,176 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.42384481s left)
2019-06-04 05:43:00,497 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054300482845
2019-06-04 05:43:00,529 [salt.minion      :1432][INFO    ][19256] Starting a new job with PID 19256
2019-06-04 05:43:00,557 [salt.minion      :1711][INFO    ][19256] Returning information for job: 20190604054300482845
2019-06-04 05:43:08,089 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.5108099s left)
2019-06-04 05:43:30,690 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054330673508
2019-06-04 05:43:30,718 [salt.minion      :1432][INFO    ][19465] Starting a new job with PID 19465
2019-06-04 05:43:30,743 [salt.minion      :1711][INFO    ][19465] Returning information for job: 20190604054330673508
2019-06-04 05:43:41,133 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.46675181s left)
2019-06-04 05:44:00,861 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054400847562
2019-06-04 05:44:00,893 [salt.minion      :1432][INFO    ][19559] Starting a new job with PID 19559
2019-06-04 05:44:00,918 [salt.minion      :1711][INFO    ][19559] Returning information for job: 20190604054400847562
2019-06-04 05:44:14,177 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.42253304s left)
2019-06-04 05:44:31,043 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054431032584
2019-06-04 05:44:31,068 [salt.minion      :1432][INFO    ][19626] Starting a new job with PID 19626
2019-06-04 05:44:31,104 [salt.minion      :1711][INFO    ][19626] Returning information for job: 20190604054431032584
2019-06-04 05:44:47,182 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.41778803s left)
2019-06-04 05:45:01,138 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054501124289
2019-06-04 05:45:01,167 [salt.minion      :1432][INFO    ][19709] Starting a new job with PID 19709
2019-06-04 05:45:01,195 [salt.minion      :1711][INFO    ][19709] Returning information for job: 20190604054501124289
2019-06-04 05:45:20,134 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.46579599s left)
2019-06-04 05:45:31,327 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054531312729
2019-06-04 05:45:31,356 [salt.minion      :1432][INFO    ][20072] Starting a new job with PID 20072
2019-06-04 05:45:31,382 [salt.minion      :1711][INFO    ][20072] Returning information for job: 20190604054531312729
2019-06-04 05:45:53,127 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.47241592s left)
2019-06-04 05:46:01,498 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054601484095
2019-06-04 05:46:01,523 [salt.minion      :1432][INFO    ][20159] Starting a new job with PID 20159
2019-06-04 05:46:01,549 [salt.minion      :1711][INFO    ][20159] Returning information for job: 20190604054601484095
2019-06-04 05:46:25,999 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.60025787s left)
2019-06-04 05:46:31,690 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054631674573
2019-06-04 05:46:31,717 [salt.minion      :1432][INFO    ][20211] Starting a new job with PID 20211
2019-06-04 05:46:31,739 [salt.minion      :1711][INFO    ][20211] Returning information for job: 20190604054631674573
2019-06-04 05:46:59,077 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1553.52311802s left)
2019-06-04 05:47:01,876 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054701868628
2019-06-04 05:47:01,895 [salt.minion      :1432][INFO    ][20235] Starting a new job with PID 20235
2019-06-04 05:47:01,919 [salt.minion      :1711][INFO    ][20235] Returning information for job: 20190604054701868628
2019-06-04 05:47:31,919 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054731907368
2019-06-04 05:47:31,945 [salt.minion      :1432][INFO    ][20410] Starting a new job with PID 20410
2019-06-04 05:47:31,978 [salt.minion      :1711][INFO    ][20410] Returning information for job: 20190604054731907368
2019-06-04 05:47:32,224 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1520.37601995s left)
2019-06-04 05:48:02,060 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054802043901
2019-06-04 05:48:02,085 [salt.minion      :1432][INFO    ][20458] Starting a new job with PID 20458
2019-06-04 05:48:02,109 [salt.minion      :1711][INFO    ][20458] Returning information for job: 20190604054802043901
2019-06-04 05:48:05,247 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1487.35224104s left)
2019-06-04 05:48:32,093 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054832079854
2019-06-04 05:48:32,122 [salt.minion      :1432][INFO    ][20671] Starting a new job with PID 20671
2019-06-04 05:48:32,147 [salt.minion      :1711][INFO    ][20671] Returning information for job: 20190604054832079854
2019-06-04 05:48:38,320 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1454.27954698s left)
2019-06-04 05:49:02,137 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054902119863
2019-06-04 05:49:02,167 [salt.minion      :1432][INFO    ][20696] Starting a new job with PID 20696
2019-06-04 05:49:02,191 [salt.minion      :1711][INFO    ][20696] Returning information for job: 20190604054902119863
2019-06-04 05:49:11,412 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1421.18821692s left)
2019-06-04 05:49:32,220 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604054932200467
2019-06-04 05:49:32,249 [salt.minion      :1432][INFO    ][20749] Starting a new job with PID 20749
2019-06-04 05:49:32,274 [salt.minion      :1711][INFO    ][20749] Returning information for job: 20190604054932200467
2019-06-04 05:49:44,334 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1388.26556587s left)
2019-06-04 05:50:02,276 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055002265443
2019-06-04 05:50:02,304 [salt.minion      :1432][INFO    ][20772] Starting a new job with PID 20772
2019-06-04 05:50:02,328 [salt.minion      :1711][INFO    ][20772] Returning information for job: 20190604055002265443
2019-06-04 05:50:17,388 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1355.21214104s left)
2019-06-04 05:50:32,349 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055032337926
2019-06-04 05:50:32,381 [salt.minion      :1432][INFO    ][20824] Starting a new job with PID 20824
2019-06-04 05:50:32,409 [salt.minion      :1711][INFO    ][20824] Returning information for job: 20190604055032337926
2019-06-04 05:50:50,145 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1322.45447683s left)
2019-06-04 05:51:02,444 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055102431514
2019-06-04 05:51:02,470 [salt.minion      :1432][INFO    ][20856] Starting a new job with PID 20856
2019-06-04 05:51:02,501 [salt.minion      :1711][INFO    ][20856] Returning information for job: 20190604055102431514
2019-06-04 05:51:23,231 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1289.36901999s left)
2019-06-04 05:51:32,516 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055132505576
2019-06-04 05:51:32,543 [salt.minion      :1432][INFO    ][20909] Starting a new job with PID 20909
2019-06-04 05:51:32,567 [salt.minion      :1711][INFO    ][20909] Returning information for job: 20190604055132505576
2019-06-04 05:51:56,067 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1256.53232694s left)
2019-06-04 05:52:02,634 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055202617333
2019-06-04 05:52:02,664 [salt.minion      :1432][INFO    ][20934] Starting a new job with PID 20934
2019-06-04 05:52:02,693 [salt.minion      :1711][INFO    ][20934] Returning information for job: 20190604055202617333
2019-06-04 05:52:29,388 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1223.21184993s left)
2019-06-04 05:52:32,755 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055232744726
2019-06-04 05:52:32,778 [salt.minion      :1432][INFO    ][21000] Starting a new job with PID 21000
2019-06-04 05:52:32,804 [salt.minion      :1711][INFO    ][21000] Returning information for job: 20190604055232744726
2019-06-04 05:53:02,179 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1190.42075586s left)
2019-06-04 05:53:02,864 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055302855053
2019-06-04 05:53:02,896 [salt.minion      :1432][INFO    ][21021] Starting a new job with PID 21021
2019-06-04 05:53:02,923 [salt.minion      :1711][INFO    ][21021] Returning information for job: 20190604055302855053
2019-06-04 05:53:33,025 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055333012926
2019-06-04 05:53:33,047 [salt.minion      :1432][INFO    ][21079] Starting a new job with PID 21079
2019-06-04 05:53:33,076 [salt.minion      :1711][INFO    ][21079] Returning information for job: 20190604055333012926
2019-06-04 05:53:35,064 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1157.53579783s left)
2019-06-04 05:54:03,145 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055403129390
2019-06-04 05:54:03,173 [salt.minion      :1432][INFO    ][21097] Starting a new job with PID 21097
2019-06-04 05:54:03,197 [salt.minion      :1711][INFO    ][21097] Returning information for job: 20190604055403129390
2019-06-04 05:54:07,873 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1124.7265799s left)
2019-06-04 05:54:33,316 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055433300608
2019-06-04 05:54:33,342 [salt.minion      :1432][INFO    ][21153] Starting a new job with PID 21153
2019-06-04 05:54:33,367 [salt.minion      :1711][INFO    ][21153] Returning information for job: 20190604055433300608
2019-06-04 05:54:40,895 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1091.7043469s left)
2019-06-04 05:55:03,485 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055503468755
2019-06-04 05:55:03,514 [salt.minion      :1432][INFO    ][21174] Starting a new job with PID 21174
2019-06-04 05:55:03,537 [salt.minion      :1711][INFO    ][21174] Returning information for job: 20190604055503468755
2019-06-04 05:55:13,684 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1058.91619992s left)
2019-06-04 05:55:33,693 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055533682551
2019-06-04 05:55:33,719 [salt.minion      :1432][INFO    ][21244] Starting a new job with PID 21244
2019-06-04 05:55:33,745 [salt.minion      :1711][INFO    ][21244] Returning information for job: 20190604055533682551
2019-06-04 05:55:46,472 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1026.12820196s left)
2019-06-04 05:56:03,877 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055603862999
2019-06-04 05:56:03,904 [salt.minion      :1432][INFO    ][21272] Starting a new job with PID 21272
2019-06-04 05:56:03,928 [salt.minion      :1711][INFO    ][21272] Returning information for job: 20190604055603862999
2019-06-04 05:56:19,470 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (993.129266977s left)
2019-06-04 05:56:33,895 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055633884267
2019-06-04 05:56:33,920 [salt.minion      :1432][INFO    ][21331] Starting a new job with PID 21331
2019-06-04 05:56:33,943 [salt.minion      :1711][INFO    ][21331] Returning information for job: 20190604055633884267
2019-06-04 05:56:52,405 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (960.194705963s left)
2019-06-04 05:57:04,104 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055704087083
2019-06-04 05:57:04,127 [salt.minion      :1432][INFO    ][21352] Starting a new job with PID 21352
2019-06-04 05:57:04,159 [salt.minion      :1711][INFO    ][21352] Returning information for job: 20190604055704087083
2019-06-04 05:57:25,119 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (927.481165886s left)
2019-06-04 05:57:34,156 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055734137087
2019-06-04 05:57:34,188 [salt.minion      :1432][INFO    ][21416] Starting a new job with PID 21416
2019-06-04 05:57:34,211 [salt.minion      :1711][INFO    ][21416] Returning information for job: 20190604055734137087
2019-06-04 05:57:58,265 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (894.334710836s left)
2019-06-04 05:58:04,191 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055804176635
2019-06-04 05:58:04,217 [salt.minion      :1432][INFO    ][21439] Starting a new job with PID 21439
2019-06-04 05:58:04,244 [salt.minion      :1711][INFO    ][21439] Returning information for job: 20190604055804176635
2019-06-04 05:58:31,246 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (861.353276968s left)
2019-06-04 05:58:34,279 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055834267106
2019-06-04 05:58:34,308 [salt.minion      :1432][INFO    ][21494] Starting a new job with PID 21494
2019-06-04 05:58:34,338 [salt.minion      :1711][INFO    ][21494] Returning information for job: 20190604055834267106
2019-06-04 05:59:04,000 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (828.600133896s left)
2019-06-04 05:59:04,378 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055904364099
2019-06-04 05:59:04,400 [salt.minion      :1432][INFO    ][21515] Starting a new job with PID 21515
2019-06-04 05:59:04,426 [salt.minion      :1711][INFO    ][21515] Returning information for job: 20190604055904364099
2019-06-04 05:59:34,519 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604055934509211
2019-06-04 05:59:34,544 [salt.minion      :1432][INFO    ][21567] Starting a new job with PID 21567
2019-06-04 05:59:34,568 [salt.minion      :1711][INFO    ][21567] Returning information for job: 20190604055934509211
2019-06-04 05:59:36,910 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (795.689641953s left)
2019-06-04 06:00:04,676 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060004663147
2019-06-04 06:00:04,704 [salt.minion      :1432][INFO    ][21587] Starting a new job with PID 21587
2019-06-04 06:00:04,731 [salt.minion      :1711][INFO    ][21587] Returning information for job: 20190604060004663147
2019-06-04 06:00:09,828 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (762.77125597s left)
2019-06-04 06:00:34,793 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060034776081
2019-06-04 06:00:34,821 [salt.minion      :1432][INFO    ][21643] Starting a new job with PID 21643
2019-06-04 06:00:34,847 [salt.minion      :1711][INFO    ][21643] Returning information for job: 20190604060034776081
2019-06-04 06:00:42,553 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (730.047119856s left)
2019-06-04 06:01:04,972 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060104957069
2019-06-04 06:01:05,000 [salt.minion      :1432][INFO    ][21668] Starting a new job with PID 21668
2019-06-04 06:01:05,024 [salt.minion      :1711][INFO    ][21668] Returning information for job: 20190604060104957069
2019-06-04 06:01:15,492 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (697.107638836s left)
2019-06-04 06:01:35,143 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060135129779
2019-06-04 06:01:35,172 [salt.minion      :1432][INFO    ][21723] Starting a new job with PID 21723
2019-06-04 06:01:35,195 [salt.minion      :1711][INFO    ][21723] Returning information for job: 20190604060135129779
2019-06-04 06:01:48,345 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (664.254894018s left)
2019-06-04 06:02:05,171 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060205155901
2019-06-04 06:02:05,200 [salt.minion      :1432][INFO    ][21748] Starting a new job with PID 21748
2019-06-04 06:02:05,229 [salt.minion      :1711][INFO    ][21748] Returning information for job: 20190604060205155901
2019-06-04 06:02:21,100 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (631.499509811s left)
2019-06-04 06:02:35,369 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060235354825
2019-06-04 06:02:35,398 [salt.minion      :1432][INFO    ][21805] Starting a new job with PID 21805
2019-06-04 06:02:35,422 [salt.minion      :1711][INFO    ][21805] Returning information for job: 20190604060235354825
2019-06-04 06:02:53,830 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (598.769837856s left)
2019-06-04 06:03:05,423 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060305412585
2019-06-04 06:03:05,451 [salt.minion      :1432][INFO    ][21826] Starting a new job with PID 21826
2019-06-04 06:03:05,479 [salt.minion      :1711][INFO    ][21826] Returning information for job: 20190604060305412585
2019-06-04 06:03:26,878 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (565.721689939s left)
2019-06-04 06:03:35,463 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060335449100
2019-06-04 06:03:35,492 [salt.minion      :1432][INFO    ][21880] Starting a new job with PID 21880
2019-06-04 06:03:35,516 [salt.minion      :1711][INFO    ][21880] Returning information for job: 20190604060335449100
2019-06-04 06:03:59,704 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (532.896193027s left)
2019-06-04 06:04:05,566 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060405551439
2019-06-04 06:04:05,597 [salt.minion      :1432][INFO    ][21901] Starting a new job with PID 21901
2019-06-04 06:04:05,620 [salt.minion      :1711][INFO    ][21901] Returning information for job: 20190604060405551439
2019-06-04 06:04:32,455 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (500.145088911s left)
2019-06-04 06:04:35,680 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060435667073
2019-06-04 06:04:35,698 [salt.minion      :1432][INFO    ][21956] Starting a new job with PID 21956
2019-06-04 06:04:35,723 [salt.minion      :1711][INFO    ][21956] Returning information for job: 20190604060435667073
2019-06-04 06:05:05,345 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (467.255226851s left)
2019-06-04 06:05:05,816 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060505801543
2019-06-04 06:05:05,842 [salt.minion      :1432][INFO    ][21978] Starting a new job with PID 21978
2019-06-04 06:05:05,867 [salt.minion      :1711][INFO    ][21978] Returning information for job: 20190604060505801543
2019-06-04 06:05:20,536 [salt.utils.schedule:1377][INFO    ][3035] Running scheduled job: __mine_interval
2019-06-04 06:05:35,977 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060535968384
2019-06-04 06:05:36,000 [salt.minion      :1432][INFO    ][22192] Starting a new job with PID 22192
2019-06-04 06:05:36,025 [salt.minion      :1711][INFO    ][22192] Returning information for job: 20190604060535968384
2019-06-04 06:05:38,535 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (434.06443882s left)
2019-06-04 06:06:06,157 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060606141323
2019-06-04 06:06:06,184 [salt.minion      :1432][INFO    ][22352] Starting a new job with PID 22352
2019-06-04 06:06:06,211 [salt.minion      :1711][INFO    ][22352] Returning information for job: 20190604060606141323
2019-06-04 06:06:11,420 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (401.17929101s left)
2019-06-04 06:06:36,391 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060636375365
2019-06-04 06:06:36,419 [salt.minion      :1432][INFO    ][22407] Starting a new job with PID 22407
2019-06-04 06:06:36,442 [salt.minion      :1711][INFO    ][22407] Returning information for job: 20190604060636375365
2019-06-04 06:06:44,373 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (368.227113962s left)
2019-06-04 06:07:06,623 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060706607268
2019-06-04 06:07:06,655 [salt.minion      :1432][INFO    ][22429] Starting a new job with PID 22429
2019-06-04 06:07:06,680 [salt.minion      :1711][INFO    ][22429] Returning information for job: 20190604060706607268
2019-06-04 06:07:17,083 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (335.516824007s left)
2019-06-04 06:07:36,664 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060736650510
2019-06-04 06:07:36,690 [salt.minion      :1432][INFO    ][22487] Starting a new job with PID 22487
2019-06-04 06:07:36,714 [salt.minion      :1711][INFO    ][22487] Returning information for job: 20190604060736650510
2019-06-04 06:07:49,875 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (302.724233866s left)
2019-06-04 06:08:06,869 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060806855042
2019-06-04 06:08:06,897 [salt.minion      :1432][INFO    ][22510] Starting a new job with PID 22510
2019-06-04 06:08:06,921 [salt.minion      :1711][INFO    ][22510] Returning information for job: 20190604060806855042
2019-06-04 06:08:22,748 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (269.851400852s left)
2019-06-04 06:08:36,912 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060836896380
2019-06-04 06:08:36,937 [salt.minion      :1432][INFO    ][22565] Starting a new job with PID 22565
2019-06-04 06:08:36,963 [salt.minion      :1711][INFO    ][22565] Returning information for job: 20190604060836896380
2019-06-04 06:08:55,915 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (236.684222937s left)
2019-06-04 06:09:06,947 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060906931082
2019-06-04 06:09:06,975 [salt.minion      :1432][INFO    ][22587] Starting a new job with PID 22587
2019-06-04 06:09:07,002 [salt.minion      :1711][INFO    ][22587] Returning information for job: 20190604060906931082
2019-06-04 06:09:28,772 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (203.828012943s left)
2019-06-04 06:09:37,065 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604060937053480
2019-06-04 06:09:37,093 [salt.minion      :1432][INFO    ][22640] Starting a new job with PID 22640
2019-06-04 06:09:37,117 [salt.minion      :1711][INFO    ][22640] Returning information for job: 20190604060937053480
2019-06-04 06:10:01,577 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (171.023089886s left)
2019-06-04 06:10:07,116 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061007099856
2019-06-04 06:10:07,143 [salt.minion      :1432][INFO    ][22663] Starting a new job with PID 22663
2019-06-04 06:10:07,183 [salt.minion      :1711][INFO    ][22663] Returning information for job: 20190604061007099856
2019-06-04 06:10:34,347 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (138.253188848s left)
2019-06-04 06:10:37,263 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061037245092
2019-06-04 06:10:37,289 [salt.minion      :1432][INFO    ][22718] Starting a new job with PID 22718
2019-06-04 06:10:37,328 [salt.minion      :1711][INFO    ][22718] Returning information for job: 20190604061037245092
2019-06-04 06:11:07,056 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (105.543741941s left)
2019-06-04 06:11:07,440 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061107433584
2019-06-04 06:11:07,462 [salt.minion      :1432][INFO    ][22739] Starting a new job with PID 22739
2019-06-04 06:11:07,494 [salt.minion      :1711][INFO    ][22739] Returning information for job: 20190604061107433584
2019-06-04 06:11:37,593 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061137584009
2019-06-04 06:11:37,625 [salt.minion      :1432][INFO    ][22795] Starting a new job with PID 22795
2019-06-04 06:11:37,656 [salt.minion      :1711][INFO    ][22795] Returning information for job: 20190604061137584009
2019-06-04 06:11:40,015 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (72.5851500034s left)
2019-06-04 06:12:07,799 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061207782787
2019-06-04 06:12:07,826 [salt.minion      :1432][INFO    ][22815] Starting a new job with PID 22815
2019-06-04 06:12:07,858 [salt.minion      :1711][INFO    ][22815] Returning information for job: 20190604061207782787
2019-06-04 06:12:13,093 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (39.5066428185s left)
2019-06-04 06:12:37,932 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061237919827
2019-06-04 06:12:37,963 [salt.minion      :1432][INFO    ][22873] Starting a new job with PID 22873
2019-06-04 06:12:37,995 [salt.minion      :1711][INFO    ][22873] Returning information for job: 20190604061237919827
2019-06-04 06:12:45,924 [salt.loaded.ext.module.maas:1023][INFO    ][17096] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (6.67569494247s left)
2019-06-04 06:13:07,985 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061307971036
2019-06-04 06:13:08,011 [salt.minion      :1432][INFO    ][22896] Starting a new job with PID 22896
2019-06-04 06:13:08,044 [salt.minion      :1711][INFO    ][22896] Returning information for job: 20190604061307971036
2019-06-04 06:13:18,789 [salt.state       :302 ][ERROR   ][17096] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-06-04 06:13:18,789 [salt.state       :1951][INFO    ][17096] Completed state [maas.wait_for_machine_status] at time 06:13:18.789744 duration_in_ms=2276206.199
2019-06-04 06:13:18,798 [salt.minion      :1711][INFO    ][17096] Returning information for job: 20190604053513360551
2019-06-04 06:13:29,795 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command pillar.get with jid 20190604061329779081
2019-06-04 06:13:29,823 [salt.minion      :1432][INFO    ][22938] Starting a new job with PID 22938
2019-06-04 06:13:29,836 [salt.minion      :1711][INFO    ][22938] Returning information for job: 20190604061329779081
2019-06-04 06:13:30,571 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command service.status with jid 20190604061330560241
2019-06-04 06:13:30,601 [salt.minion      :1432][INFO    ][22943] Starting a new job with PID 22943
2019-06-04 06:13:31,944 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][22943] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-04 06:13:31,999 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][22943] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-04 06:13:32,022 [salt.minion      :1711][INFO    ][22943] Returning information for job: 20190604061330560241
2019-06-04 06:13:32,783 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604061332766596
2019-06-04 06:13:32,814 [salt.minion      :1432][INFO    ][22969] Starting a new job with PID 22969
2019-06-04 06:13:39,198 [salt.state       :915 ][INFO    ][22969] Loading fresh modules for state activity
2019-06-04 06:13:39,851 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command 'salt-minion --version' in directory '/root'
2019-06-04 06:13:40,397 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command 'salt-minion --version' in directory '/root'
2019-06-04 06:13:41,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command 'salt-minion --version' in directory '/root'
2019-06-04 06:13:41,862 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command 'salt-minion --version' in directory '/root'
2019-06-04 06:13:44,539 [salt.state       :1780][INFO    ][22969] Running state [salt-minion] at time 06:13:44.539892
2019-06-04 06:13:44,540 [salt.state       :1813][INFO    ][22969] Executing state pkg.installed for [salt-minion]
2019-06-04 06:13:44,541 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-04 06:13:44,686 [salt.state       :300 ][INFO    ][22969] All specified packages are already installed
2019-06-04 06:13:44,686 [salt.state       :1951][INFO    ][22969] Completed state [salt-minion] at time 06:13:44.686760 duration_in_ms=146.869
2019-06-04 06:13:44,687 [salt.state       :1780][INFO    ][22969] Running state [salt_minion_dependency_packages] at time 06:13:44.687138
2019-06-04 06:13:44,687 [salt.state       :1813][INFO    ][22969] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-04 06:13:44,697 [salt.state       :300 ][INFO    ][22969] All specified packages are already installed
2019-06-04 06:13:44,697 [salt.state       :1951][INFO    ][22969] Completed state [salt_minion_dependency_packages] at time 06:13:44.697579 duration_in_ms=10.441
2019-06-04 06:13:44,704 [salt.state       :1780][INFO    ][22969] Running state [/etc/salt/minion.d/minion.conf] at time 06:13:44.704823
2019-06-04 06:13:44,705 [salt.state       :1813][INFO    ][22969] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-04 06:13:44,999 [salt.state       :300 ][INFO    ][22969] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-04 06:13:45,000 [salt.state       :1951][INFO    ][22969] Completed state [/etc/salt/minion.d/minion.conf] at time 06:13:44.999951 duration_in_ms=295.128
2019-06-04 06:13:45,000 [salt.state       :1780][INFO    ][22969] Running state [python-netaddr] at time 06:13:45.000494
2019-06-04 06:13:45,001 [salt.state       :1813][INFO    ][22969] Executing state pkg.installed for [python-netaddr]
2019-06-04 06:13:45,015 [salt.state       :300 ][INFO    ][22969] All specified packages are already installed
2019-06-04 06:13:45,016 [salt.state       :1951][INFO    ][22969] Completed state [python-netaddr] at time 06:13:45.015992 duration_in_ms=15.498
2019-06-04 06:13:45,022 [salt.state       :1780][INFO    ][22969] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:13:45.022381
2019-06-04 06:13:45,022 [salt.state       :1813][INFO    ][22969] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-04 06:13:45,046 [salt.state       :300 ][INFO    ][22969] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-04 06:13:45,047 [salt.state       :1951][INFO    ][22969] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:13:45.046999 duration_in_ms=24.618
2019-06-04 06:13:45,049 [salt.state       :1780][INFO    ][22969] Running state [salt-minion] at time 06:13:45.048948
2019-06-04 06:13:45,049 [salt.state       :1813][INFO    ][22969] Executing state service.running for [salt-minion]
2019-06-04 06:13:45,052 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-04 06:13:45,095 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-04 06:13:45,118 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-04 06:13:45,137 [salt.state       :300 ][INFO    ][22969] The service salt-minion is already running
2019-06-04 06:13:45,138 [salt.state       :1951][INFO    ][22969] Completed state [salt-minion] at time 06:13:45.137691 duration_in_ms=88.742
2019-06-04 06:13:45,141 [salt.state       :1780][INFO    ][22969] Running state [/etc/salt/grains.d] at time 06:13:45.141319
2019-06-04 06:13:45,142 [salt.state       :1813][INFO    ][22969] Executing state file.directory for [/etc/salt/grains.d]
2019-06-04 06:13:45,144 [salt.state       :300 ][INFO    ][22969] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-04 06:13:45,144 [salt.state       :1951][INFO    ][22969] Completed state [/etc/salt/grains.d] at time 06:13:45.144732 duration_in_ms=3.413
2019-06-04 06:13:45,147 [salt.state       :1780][INFO    ][22969] Running state [/etc/salt/grains] at time 06:13:45.147932
2019-06-04 06:13:45,148 [salt.state       :1813][INFO    ][22969] Executing state file.managed for [/etc/salt/grains]
2019-06-04 06:13:45,149 [salt.state       :300 ][INFO    ][22969] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-04 06:13:45,149 [salt.state       :1951][INFO    ][22969] Completed state [/etc/salt/grains] at time 06:13:45.149363 duration_in_ms=1.431
2019-06-04 06:13:45,150 [salt.state       :1780][INFO    ][22969] Running state [/etc/salt/grains.d/placeholder] at time 06:13:45.149970
2019-06-04 06:13:45,150 [salt.state       :1813][INFO    ][22969] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-04 06:13:45,150 [salt.state       :300 ][INFO    ][22969] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-04 06:13:45,151 [salt.state       :1951][INFO    ][22969] Completed state [/etc/salt/grains.d/placeholder] at time 06:13:45.151146 duration_in_ms=1.176
2019-06-04 06:13:45,151 [salt.state       :1780][INFO    ][22969] Running state [/etc/salt/grains.d/sphinx] at time 06:13:45.151709
2019-06-04 06:13:45,152 [salt.state       :1813][INFO    ][22969] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-04 06:13:45,153 [salt.state       :300 ][INFO    ][22969] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-04 06:13:45,153 [salt.state       :1951][INFO    ][22969] Completed state [/etc/salt/grains.d/sphinx] at time 06:13:45.153859 duration_in_ms=2.15
2019-06-04 06:13:45,156 [salt.state       :1780][INFO    ][22969] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:13:45.156087
2019-06-04 06:13:45,156 [salt.state       :1813][INFO    ][22969] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-04 06:13:45,156 [salt.state       :300 ][INFO    ][22969] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-04 06:13:45,157 [salt.state       :1951][INFO    ][22969] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:13:45.157016 duration_in_ms=0.929
2019-06-04 06:13:45,157 [salt.state       :1780][INFO    ][22969] Running state [/etc/salt/grains.d/dns_records] at time 06:13:45.157569
2019-06-04 06:13:45,159 [salt.state       :1813][INFO    ][22969] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-04 06:13:45,161 [salt.state       :300 ][INFO    ][22969] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-04 06:13:45,161 [salt.state       :1951][INFO    ][22969] Completed state [/etc/salt/grains.d/dns_records] at time 06:13:45.161304 duration_in_ms=3.736
2019-06-04 06:13:45,162 [salt.state       :1780][INFO    ][22969] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:13:45.162889
2019-06-04 06:13:45,163 [salt.state       :1813][INFO    ][22969] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-04 06:13:45,163 [salt.state       :300 ][INFO    ][22969] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-04 06:13:45,163 [salt.state       :1951][INFO    ][22969] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:13:45.163749 duration_in_ms=0.86
2019-06-04 06:13:45,164 [salt.state       :1780][INFO    ][22969] Running state [/etc/salt/grains.d/salt] at time 06:13:45.164274
2019-06-04 06:13:45,164 [salt.state       :1813][INFO    ][22969] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-04 06:13:45,166 [salt.state       :300 ][INFO    ][22969] File /etc/salt/grains.d/salt is in the correct state
2019-06-04 06:13:45,166 [salt.state       :1951][INFO    ][22969] Completed state [/etc/salt/grains.d/salt] at time 06:13:45.166812 duration_in_ms=2.537
2019-06-04 06:13:45,168 [salt.state       :1780][INFO    ][22969] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:13:45.168847
2019-06-04 06:13:45,169 [salt.state       :1813][INFO    ][22969] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-04 06:13:45,170 [salt.state       :300 ][INFO    ][22969] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-04 06:13:45,171 [salt.state       :1951][INFO    ][22969] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:13:45.171212 duration_in_ms=2.365
2019-06-04 06:13:45,175 [salt.state       :1780][INFO    ][22969] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:13:45.175692
2019-06-04 06:13:45,176 [salt.state       :1813][INFO    ][22969] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-04 06:13:45,176 [salt.state       :300 ][INFO    ][22969] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-04 06:13:45,177 [salt.state       :1951][INFO    ][22969] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:13:45.177031 duration_in_ms=1.34
2019-06-04 06:13:45,178 [salt.state       :1780][INFO    ][22969] Running state [mine.update] at time 06:13:45.178140
2019-06-04 06:13:45,178 [salt.state       :1813][INFO    ][22969] Executing state module.wait for [mine.update]
2019-06-04 06:13:45,179 [salt.state       :300 ][INFO    ][22969] No changes made for mine.update
2019-06-04 06:13:45,179 [salt.state       :1951][INFO    ][22969] Completed state [mine.update] at time 06:13:45.179327 duration_in_ms=1.187
2019-06-04 06:13:45,179 [salt.state       :1780][INFO    ][22969] Running state [ca-certificates] at time 06:13:45.179711
2019-06-04 06:13:45,180 [salt.state       :1813][INFO    ][22969] Executing state pkg.installed for [ca-certificates]
2019-06-04 06:13:45,192 [salt.state       :300 ][INFO    ][22969] All specified packages are already installed
2019-06-04 06:13:45,192 [salt.state       :1951][INFO    ][22969] Completed state [ca-certificates] at time 06:13:45.192913 duration_in_ms=13.202
2019-06-04 06:13:45,194 [salt.state       :1780][INFO    ][22969] Running state [update-ca-certificates] at time 06:13:45.193659
2019-06-04 06:13:45,195 [salt.state       :1813][INFO    ][22969] Executing state cmd.wait for [update-ca-certificates]
2019-06-04 06:13:45,195 [salt.state       :300 ][INFO    ][22969] No changes made for update-ca-certificates
2019-06-04 06:13:45,195 [salt.state       :1951][INFO    ][22969] Completed state [update-ca-certificates] at time 06:13:45.195517 duration_in_ms=1.858
2019-06-04 06:13:45,195 [salt.state       :1780][INFO    ][22969] Running state [iptables] at time 06:13:45.195792
2019-06-04 06:13:45,196 [salt.state       :1813][INFO    ][22969] Executing state pkg.installed for [iptables]
2019-06-04 06:13:45,205 [salt.state       :300 ][INFO    ][22969] All specified packages are already installed
2019-06-04 06:13:45,205 [salt.state       :1951][INFO    ][22969] Completed state [iptables] at time 06:13:45.205478 duration_in_ms=9.685
2019-06-04 06:13:45,206 [salt.state       :1780][INFO    ][22969] Running state [iptables-persistent] at time 06:13:45.206447
2019-06-04 06:13:45,206 [salt.state       :1813][INFO    ][22969] Executing state pkg.installed for [iptables-persistent]
2019-06-04 06:13:45,215 [salt.state       :300 ][INFO    ][22969] All specified packages are already installed
2019-06-04 06:13:45,216 [salt.state       :1951][INFO    ][22969] Completed state [iptables-persistent] at time 06:13:45.216144 duration_in_ms=9.697
2019-06-04 06:13:45,219 [salt.state       :1780][INFO    ][22969] Running state [iptables_modules_v4_load] at time 06:13:45.218925
2019-06-04 06:13:45,219 [salt.state       :1813][INFO    ][22969] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-04 06:13:45,220 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command 'lsmod' in directory '/root'
2019-06-04 06:13:45,249 [salt.state       :300 ][INFO    ][22969] Kernel modules iptable_filter, ip_tables are already present
2019-06-04 06:13:45,250 [salt.state       :1951][INFO    ][22969] Completed state [iptables_modules_v4_load] at time 06:13:45.250297 duration_in_ms=31.372
2019-06-04 06:13:45,251 [salt.state       :1780][INFO    ][22969] Running state [/etc/iptables/rules.v4] at time 06:13:45.251266
2019-06-04 06:13:45,251 [salt.state       :1813][INFO    ][22969] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-04 06:13:45,372 [salt.state       :300 ][INFO    ][22969] File /etc/iptables/rules.v4 is in the correct state
2019-06-04 06:13:45,372 [salt.state       :1951][INFO    ][22969] Completed state [/etc/iptables/rules.v4] at time 06:13:45.372917 duration_in_ms=121.651
2019-06-04 06:13:45,375 [salt.state       :1780][INFO    ][22969] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:13:45.375285
2019-06-04 06:13:45,375 [salt.state       :1813][INFO    ][22969] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-04 06:13:45,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-04 06:13:45,401 [salt.state       :300 ][INFO    ][22969] onlyif execution failed
2019-06-04 06:13:45,402 [salt.state       :1951][INFO    ][22969] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:13:45.402003 duration_in_ms=26.719
2019-06-04 06:13:45,403 [salt.state       :1780][INFO    ][22969] Running state [netfilter-persistent] at time 06:13:45.403706
2019-06-04 06:13:45,404 [salt.state       :1813][INFO    ][22969] Executing state service.running for [netfilter-persistent]
2019-06-04 06:13:45,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-04 06:13:45,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-04 06:13:45,460 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-04 06:13:45,479 [salt.state       :300 ][INFO    ][22969] The service netfilter-persistent is already running
2019-06-04 06:13:45,480 [salt.state       :1951][INFO    ][22969] Completed state [netfilter-persistent] at time 06:13:45.480056 duration_in_ms=76.35
2019-06-04 06:13:45,481 [salt.state       :1780][INFO    ][22969] Running state [iptables_extra.remove_stale_tables] at time 06:13:45.481289
2019-06-04 06:13:45,483 [salt.state       :1813][INFO    ][22969] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-04 06:13:45,484 [salt.state       :300 ][INFO    ][22969] No changes made for iptables_extra.remove_stale_tables
2019-06-04 06:13:45,484 [salt.state       :1951][INFO    ][22969] Completed state [iptables_extra.remove_stale_tables] at time 06:13:45.484404 duration_in_ms=3.115
2019-06-04 06:13:45,484 [salt.state       :1780][INFO    ][22969] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:13:45.484768
2019-06-04 06:13:45,485 [salt.state       :1813][INFO    ][22969] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-04 06:13:45,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-04 06:13:45,503 [salt.state       :300 ][INFO    ][22969] onlyif execution failed
2019-06-04 06:13:45,504 [salt.state       :1951][INFO    ][22969] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:13:45.504299 duration_in_ms=19.531
2019-06-04 06:13:45,505 [salt.state       :1780][INFO    ][22969] Running state [/etc/iptables/rules.v6] at time 06:13:45.505512
2019-06-04 06:13:45,506 [salt.state       :1813][INFO    ][22969] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-04 06:13:45,507 [salt.state       :300 ][INFO    ][22969] File /etc/iptables/rules.v6 is not present
2019-06-04 06:13:45,507 [salt.state       :1951][INFO    ][22969] Completed state [/etc/iptables/rules.v6] at time 06:13:45.507600 duration_in_ms=2.089
2019-06-04 06:13:45,508 [salt.state       :1780][INFO    ][22969] Running state [iptables_extra.flush_all] at time 06:13:45.508441
2019-06-04 06:13:45,508 [salt.state       :1813][INFO    ][22969] Executing state module.wait for [iptables_extra.flush_all]
2019-06-04 06:13:45,509 [salt.state       :300 ][INFO    ][22969] No changes made for iptables_extra.flush_all
2019-06-04 06:13:45,509 [salt.state       :1951][INFO    ][22969] Completed state [iptables_extra.flush_all] at time 06:13:45.509405 duration_in_ms=0.964
2019-06-04 06:13:45,516 [salt.minion      :1711][INFO    ][22969] Returning information for job: 20190604061332766596
2019-06-04 06:13:46,244 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604061346233844
2019-06-04 06:13:46,276 [salt.minion      :1432][INFO    ][23049] Starting a new job with PID 23049
2019-06-04 06:13:47,313 [salt.state       :915 ][INFO    ][23049] Loading fresh modules for state activity
2019-06-04 06:13:48,572 [salt.state       :1780][INFO    ][23049] Running state [maas-rack-controller] at time 06:13:48.572354
2019-06-04 06:13:48,572 [salt.state       :1813][INFO    ][23049] Executing state pkg.installed for [maas-rack-controller]
2019-06-04 06:13:48,573 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23049] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-04 06:13:48,690 [salt.state       :300 ][INFO    ][23049] All specified packages are already installed
2019-06-04 06:13:48,691 [salt.state       :1951][INFO    ][23049] Completed state [maas-rack-controller] at time 06:13:48.691039 duration_in_ms=118.686
2019-06-04 06:13:48,691 [salt.state       :1780][INFO    ][23049] Running state [ipmitool] at time 06:13:48.691408
2019-06-04 06:13:48,691 [salt.state       :1813][INFO    ][23049] Executing state pkg.installed for [ipmitool]
2019-06-04 06:13:48,701 [salt.state       :300 ][INFO    ][23049] All specified packages are already installed
2019-06-04 06:13:48,701 [salt.state       :1951][INFO    ][23049] Completed state [ipmitool] at time 06:13:48.701460 duration_in_ms=10.052
2019-06-04 06:13:48,704 [salt.state       :1780][INFO    ][23049] Running state [/etc/maas/rackd.conf] at time 06:13:48.704513
2019-06-04 06:13:48,704 [salt.state       :1813][INFO    ][23049] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-04 06:13:48,705 [salt.state       :300 ][INFO    ][23049] No changes needed to be made
2019-06-04 06:13:48,706 [salt.state       :1951][INFO    ][23049] Completed state [/etc/maas/rackd.conf] at time 06:13:48.706172 duration_in_ms=1.659
2019-06-04 06:13:48,707 [salt.state       :1780][INFO    ][23049] Running state [/etc/maas/rackd.conf] at time 06:13:48.707170
2019-06-04 06:13:48,707 [salt.state       :1813][INFO    ][23049] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-04 06:13:48,708 [salt.loaded.int.states.file:2298][WARNING ][23049] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-04 06:13:48,711 [salt.state       :300 ][INFO    ][23049] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-04 06:13:48,711 [salt.state       :1951][INFO    ][23049] Completed state [/etc/maas/rackd.conf] at time 06:13:48.711290 duration_in_ms=4.12
2019-06-04 06:13:48,713 [salt.state       :1780][INFO    ][23049] Running state [maas-rackd] at time 06:13:48.713485
2019-06-04 06:13:48,714 [salt.state       :1813][INFO    ][23049] Executing state service.running for [maas-rackd]
2019-06-04 06:13:48,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23049] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-04 06:13:48,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23049] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-04 06:13:48,785 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23049] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-04 06:13:48,805 [salt.state       :300 ][INFO    ][23049] The service maas-rackd is already running
2019-06-04 06:13:48,807 [salt.state       :1951][INFO    ][23049] Completed state [maas-rackd] at time 06:13:48.807200 duration_in_ms=93.715
2019-06-04 06:13:48,809 [salt.minion      :1711][INFO    ][23049] Returning information for job: 20190604061346233844
2019-06-04 06:13:49,519 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604061349506471
2019-06-04 06:13:49,549 [salt.minion      :1432][INFO    ][23072] Starting a new job with PID 23072
2019-06-04 06:13:50,547 [salt.state       :915 ][INFO    ][23072] Loading fresh modules for state activity
2019-06-04 06:13:51,976 [salt.state       :1780][INFO    ][23072] Running state [maas-region-controller] at time 06:13:51.976620
2019-06-04 06:13:51,977 [salt.state       :1813][INFO    ][23072] Executing state pkg.installed for [maas-region-controller]
2019-06-04 06:13:51,978 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-04 06:13:52,101 [salt.state       :300 ][INFO    ][23072] All specified packages are already installed
2019-06-04 06:13:52,102 [salt.state       :1951][INFO    ][23072] Completed state [maas-region-controller] at time 06:13:52.102148 duration_in_ms=125.529
2019-06-04 06:13:52,102 [salt.state       :1780][INFO    ][23072] Running state [python-oauth] at time 06:13:52.102508
2019-06-04 06:13:52,102 [salt.state       :1813][INFO    ][23072] Executing state pkg.installed for [python-oauth]
2019-06-04 06:13:52,112 [salt.state       :300 ][INFO    ][23072] All specified packages are already installed
2019-06-04 06:13:52,112 [salt.state       :1951][INFO    ][23072] Completed state [python-oauth] at time 06:13:52.112478 duration_in_ms=9.97
2019-06-04 06:13:52,115 [salt.state       :1780][INFO    ][23072] Running state [/etc/maas/regiond.conf] at time 06:13:52.115626
2019-06-04 06:13:52,115 [salt.state       :1813][INFO    ][23072] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-04 06:13:52,121 [salt.state       :300 ][INFO    ][23072] No changes needed to be made
2019-06-04 06:13:52,122 [salt.state       :1951][INFO    ][23072] Completed state [/etc/maas/regiond.conf] at time 06:13:52.122279 duration_in_ms=6.653
2019-06-04 06:13:52,122 [salt.state       :1780][INFO    ][23072] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:13:52.122813
2019-06-04 06:13:52,123 [salt.state       :1813][INFO    ][23072] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-04 06:13:52,192 [salt.state       :300 ][INFO    ][23072] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-04 06:13:52,192 [salt.state       :1951][INFO    ][23072] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:13:52.192483 duration_in_ms=69.669
2019-06-04 06:13:52,193 [salt.state       :1780][INFO    ][23072] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:13:52.193098
2019-06-04 06:13:52,193 [salt.state       :1813][INFO    ][23072] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-04 06:13:52,199 [salt.state       :300 ][INFO    ][23072] No changes needed to be made
2019-06-04 06:13:52,199 [salt.state       :1951][INFO    ][23072] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:13:52.199811 duration_in_ms=6.713
2019-06-04 06:13:52,200 [salt.state       :1780][INFO    ][23072] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:13:52.200396
2019-06-04 06:13:52,200 [salt.state       :1813][INFO    ][23072] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-04 06:13:52,206 [salt.state       :300 ][INFO    ][23072] No changes needed to be made
2019-06-04 06:13:52,206 [salt.state       :1951][INFO    ][23072] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:13:52.206626 duration_in_ms=6.23
2019-06-04 06:13:52,207 [salt.state       :1780][INFO    ][23072] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:13:52.207163
2019-06-04 06:13:52,207 [salt.state       :1813][INFO    ][23072] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-04 06:13:52,234 [salt.state       :300 ][INFO    ][23072] No changes needed to be made
2019-06-04 06:13:52,234 [salt.state       :1951][INFO    ][23072] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:13:52.234705 duration_in_ms=27.542
2019-06-04 06:13:52,235 [salt.state       :1780][INFO    ][23072] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:13:52.235315
2019-06-04 06:13:52,235 [salt.state       :1813][INFO    ][23072] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-04 06:13:52,253 [salt.state       :300 ][INFO    ][23072] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-04 06:13:52,254 [salt.state       :1951][INFO    ][23072] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:13:52.254026 duration_in_ms=18.711
2019-06-04 06:13:52,255 [salt.state       :1780][INFO    ][23072] Running state [a2enmod headers] at time 06:13:52.255388
2019-06-04 06:13:52,255 [salt.state       :1813][INFO    ][23072] Executing state cmd.run for [a2enmod headers]
2019-06-04 06:13:52,256 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command 'a2enmod headers' in directory '/root'
2019-06-04 06:13:52,335 [salt.state       :300 ][INFO    ][23072] {'pid': 23091, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-04 06:13:52,336 [salt.state       :1951][INFO    ][23072] Completed state [a2enmod headers] at time 06:13:52.336117 duration_in_ms=80.728
2019-06-04 06:13:52,336 [salt.state       :1780][INFO    ][23072] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:13:52.336854
2019-06-04 06:13:52,338 [salt.state       :1813][INFO    ][23072] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-04 06:13:52,365 [salt.state       :300 ][INFO    ][23072] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-04 06:13:52,365 [salt.state       :1951][INFO    ][23072] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:13:52.365496 duration_in_ms=28.642
2019-06-04 06:13:52,366 [salt.state       :1780][INFO    ][23072] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:13:52.366845
2019-06-04 06:13:52,367 [salt.state       :1813][INFO    ][23072] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-04 06:13:52,436 [salt.state       :300 ][INFO    ][23072] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-04 06:13:52,437 [salt.state       :1951][INFO    ][23072] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:13:52.437216 duration_in_ms=70.37
2019-06-04 06:13:52,438 [salt.state       :1780][INFO    ][23072] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:13:52.438193
2019-06-04 06:13:52,438 [salt.state       :1813][INFO    ][23072] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-04 06:13:52,508 [salt.state       :300 ][INFO    ][23072] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-04 06:13:52,509 [salt.state       :1951][INFO    ][23072] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:13:52.509042 duration_in_ms=70.85
2019-06-04 06:13:52,509 [salt.state       :1780][INFO    ][23072] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:13:52.509631
2019-06-04 06:13:52,509 [salt.state       :1813][INFO    ][23072] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-04 06:13:52,597 [salt.state       :300 ][INFO    ][23072] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-04 06:13:52,597 [salt.state       :1951][INFO    ][23072] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:13:52.597530 duration_in_ms=87.899
2019-06-04 06:13:52,598 [salt.state       :1780][INFO    ][23072] Running state [/root/.pgpass] at time 06:13:52.598278
2019-06-04 06:13:52,598 [salt.state       :1813][INFO    ][23072] Executing state file.managed for [/root/.pgpass]
2019-06-04 06:13:52,649 [salt.state       :300 ][INFO    ][23072] File /root/.pgpass is in the correct state
2019-06-04 06:13:52,650 [salt.state       :1951][INFO    ][23072] Completed state [/root/.pgpass] at time 06:13:52.650148 duration_in_ms=51.87
2019-06-04 06:13:52,655 [salt.state       :1780][INFO    ][23072] Running state [maas-region syncdb --noinput] at time 06:13:52.655275
2019-06-04 06:13:52,655 [salt.state       :1813][INFO    ][23072] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-04 06:13:52,656 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-04 06:13:55,612 [salt.state       :300 ][INFO    ][23072] {'pid': 23104, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, contenttypes, sessions, auth, maasserver, piston3, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-04 06:13:55,613 [salt.state       :1951][INFO    ][23072] Completed state [maas-region syncdb --noinput] at time 06:13:55.613003 duration_in_ms=2957.728
2019-06-04 06:13:55,613 [salt.state       :2022][WARNING ][23072] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-04 06:13:55,615 [salt.state       :1780][INFO    ][23072] Running state [maas-regiond] at time 06:13:55.615765
2019-06-04 06:13:55,616 [salt.state       :1813][INFO    ][23072] Executing state service.running for [maas-regiond]
2019-06-04 06:13:55,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-04 06:13:55,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-04 06:13:55,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-04 06:13:55,686 [salt.state       :300 ][INFO    ][23072] The service maas-regiond is already running
2019-06-04 06:13:55,686 [salt.state       :1951][INFO    ][23072] Completed state [maas-regiond] at time 06:13:55.686861 duration_in_ms=71.096
2019-06-04 06:13:55,688 [salt.state       :1780][INFO    ][23072] Running state [bind9] at time 06:13:55.688768
2019-06-04 06:13:55,689 [salt.state       :1813][INFO    ][23072] Executing state service.running for [bind9]
2019-06-04 06:13:55,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-04 06:13:55,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-04 06:13:55,724 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-04 06:13:55,738 [salt.state       :300 ][INFO    ][23072] The service bind9 is already running
2019-06-04 06:13:55,738 [salt.state       :1951][INFO    ][23072] Completed state [bind9] at time 06:13:55.738593 duration_in_ms=49.824
2019-06-04 06:13:55,740 [salt.state       :1780][INFO    ][23072] Running state [apache2] at time 06:13:55.740486
2019-06-04 06:13:55,740 [salt.state       :1813][INFO    ][23072] Executing state service.running for [apache2]
2019-06-04 06:13:55,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-04 06:13:55,760 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-04 06:13:55,776 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-04 06:13:55,809 [salt.state       :300 ][INFO    ][23072] The service apache2 is already running
2019-06-04 06:13:55,809 [salt.state       :1951][INFO    ][23072] Completed state [apache2] at time 06:13:55.809703 duration_in_ms=69.215
2019-06-04 06:13:55,811 [salt.state       :1780][INFO    ][23072] Running state [maasng.wait_for_http_code] at time 06:13:55.811605
2019-06-04 06:13:55,812 [salt.state       :1813][INFO    ][23072] Executing state module.run for [maasng.wait_for_http_code]
2019-06-04 06:13:55,812 [salt.utils.decorators:613 ][WARNING ][23072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 06:13:55,973 [salt.state       :300 ][INFO    ][23072] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-04 06:13:55,973 [salt.state       :1951][INFO    ][23072] Completed state [maasng.wait_for_http_code] at time 06:13:55.973398 duration_in_ms=161.792
2019-06-04 06:13:55,974 [salt.state       :1780][INFO    ][23072] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:13:55.974737
2019-06-04 06:13:55,975 [salt.state       :1813][INFO    ][23072] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-04 06:13:55,975 [salt.state       :300 ][INFO    ][23072] /var/lib/maas/.setup_admin exists
2019-06-04 06:13:55,975 [salt.state       :1951][INFO    ][23072] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:13:55.975867 duration_in_ms=1.129
2019-06-04 06:13:55,976 [salt.state       :1780][INFO    ][23072] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:55.976624
2019-06-04 06:13:55,976 [salt.state       :1813][INFO    ][23072] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-04 06:13:55,977 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23072] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-04 06:13:58,042 [salt.state       :300 ][INFO    ][23072] {'pid': 23127, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-04 06:13:58,043 [salt.state       :1951][INFO    ][23072] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:58.043237 duration_in_ms=2066.612
2019-06-04 06:13:58,056 [salt.state       :1780][INFO    ][23072] Running state [maas_region_boot_source_resources_mirror] at time 06:13:58.056097
2019-06-04 06:13:58,056 [salt.state       :1813][INFO    ][23072] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-04 06:13:58,151 [salt.state       :300 ][INFO    ][23072] {'changes': {}}
2019-06-04 06:13:58,151 [salt.state       :1951][INFO    ][23072] Completed state [maas_region_boot_source_resources_mirror] at time 06:13:58.151785 duration_in_ms=95.687
2019-06-04 06:13:58,153 [salt.state       :1780][INFO    ][23072] Running state [maasng.boot_resources_import] at time 06:13:58.153090
2019-06-04 06:13:58,155 [salt.state       :1813][INFO    ][23072] Executing state module.run for [maasng.boot_resources_import]
2019-06-04 06:13:58,156 [salt.utils.decorators:613 ][WARNING ][23072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 06:13:58,260 [salt.loaded.ext.module.maasng:1600][INFO    ][23072] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-04 06:14:03,316 [salt.loaded.ext.module.maasng:1600][INFO    ][23072] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-04 06:14:04,612 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061404599569
2019-06-04 06:14:04,640 [salt.minion      :1432][INFO    ][23160] Starting a new job with PID 23160
2019-06-04 06:14:04,670 [salt.minion      :1711][INFO    ][23160] Returning information for job: 20190604061404599569
2019-06-04 06:14:08,420 [salt.state       :300 ][INFO    ][23072] {'ret': True}
2019-06-04 06:14:08,421 [salt.state       :1951][INFO    ][23072] Completed state [maasng.boot_resources_import] at time 06:14:08.420941 duration_in_ms=10267.85
2019-06-04 06:14:08,422 [salt.state       :1780][INFO    ][23072] Running state [maas_region_boot_sources_selection_xenial] at time 06:14:08.422725
2019-06-04 06:14:08,423 [salt.state       :1813][INFO    ][23072] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-04 06:14:08,607 [salt.state       :300 ][INFO    ][23072] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-04 06:14:08,607 [salt.state       :1951][INFO    ][23072] Completed state [maas_region_boot_sources_selection_xenial] at time 06:14:08.607366 duration_in_ms=184.641
2019-06-04 06:14:08,608 [salt.state       :1780][INFO    ][23072] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:14:08.608802
2019-06-04 06:14:08,609 [salt.state       :1813][INFO    ][23072] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-04 06:14:08,609 [salt.utils.decorators:613 ][WARNING ][23072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 06:14:08,611 [salt.loaded.ext.module.maasng:1771][INFO    ][23072] boot-sources sync initiated for ALL Rack's
2019-06-04 06:14:09,665 [salt.state       :300 ][INFO    ][23072] {'ret': True}
2019-06-04 06:14:09,666 [salt.state       :1951][INFO    ][23072] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:14:09.665641 duration_in_ms=1056.838
2019-06-04 06:14:09,668 [salt.state       :1780][INFO    ][23072] Running state [maas.process_maas_config] at time 06:14:09.668821
2019-06-04 06:14:09,669 [salt.state       :1813][INFO    ][23072] Executing state module.run for [maas.process_maas_config]
2019-06-04 06:14:09,670 [salt.utils.decorators:613 ][WARNING ][23072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 06:14:09,671 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=enable_http_proxy value=True
2019-06-04 06:14:09,724 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=upstream_dns value=8.8.8.8
2019-06-04 06:14:11,370 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=commissioning_distro_series value=xenial
2019-06-04 06:14:11,436 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=default_osystem value=ubuntu
2019-06-04 06:14:11,520 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=active_discovery_interval value=600
2019-06-04 06:14:11,580 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=dnssec_validation value=no
2019-06-04 06:14:11,632 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=maas_name value=mas01
2019-06-04 06:14:11,689 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=network_discovery value=enabled
2019-06-04 06:14:11,792 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=enable_third_party_drivers value=True
2019-06-04 06:14:11,861 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=default_storage_layout value=lvm
2019-06-04 06:14:11,907 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=ntp_external_only value=True
2019-06-04 06:14:11,953 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-04 06:14:11,997 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=default_distro_series value=xenial
2019-06-04 06:14:12,079 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-04 06:14:12,225 [salt.state       :300 ][INFO    ][23072] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-04 06:14:12,226 [salt.state       :1951][INFO    ][23072] Completed state [maas.process_maas_config] at time 06:14:12.225681 duration_in_ms=2556.86
2019-06-04 06:14:12,226 [salt.state       :1780][INFO    ][23072] Running state [pxe_admin] at time 06:14:12.226730
2019-06-04 06:14:12,227 [salt.state       :1813][INFO    ][23072] Executing state maasng.fabric_present for [pxe_admin]
2019-06-04 06:14:12,299 [salt.loaded.ext.module.maasng:945 ][INFO    ][23072] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-04 06:14:12,361 [salt.loaded.ext.module.maasng:1008][WARNING ][23072] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-04 06:14:12,361 [salt.loaded.ext.module.maasng:1011][WARNING ][23072] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-04 06:14:12,441 [salt.state       :300 ][INFO    ][23072] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-04 06:14:12,441 [salt.state       :1951][INFO    ][23072] Completed state [pxe_admin] at time 06:14:12.441349 duration_in_ms=214.618
2019-06-04 06:14:12,443 [salt.state       :1780][INFO    ][23072] Running state [vlan 0] at time 06:14:12.442997
2019-06-04 06:14:12,443 [salt.state       :1813][INFO    ][23072] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-04 06:14:12,500 [salt.loaded.ext.module.maasng:945 ][INFO    ][23072] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-04 06:14:12,605 [salt.loaded.ext.module.maasng:945 ][INFO    ][23072] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-04 06:14:12,851 [salt.loaded.ext.module.maasng:945 ][INFO    ][23072] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hasksm', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-06-04 06:14:12,953 [salt.state       :300 ][INFO    ][23072] {'new': 'Vlan untagged was updated'}
2019-06-04 06:14:12,954 [salt.state       :1951][INFO    ][23072] Completed state [vlan 0] at time 06:14:12.954316 duration_in_ms=511.319
2019-06-04 06:14:12,955 [salt.state       :1780][INFO    ][23072] Running state [192.168.11.0/24] at time 06:14:12.955732
2019-06-04 06:14:12,956 [salt.state       :1813][INFO    ][23072] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-04 06:14:13,161 [salt.loaded.ext.module.maasng:945 ][INFO    ][23072] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-04 06:14:13,162 [salt.loaded.ext.module.maasng:1235][WARNING ][23072] Ignoring parameter vlan:0
2019-06-04 06:14:13,243 [salt.state       :300 ][INFO    ][23072] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-04 06:14:13,244 [salt.state       :1951][INFO    ][23072] Completed state [192.168.11.0/24] at time 06:14:13.244129 duration_in_ms=288.396
2019-06-04 06:14:13,247 [salt.state       :1780][INFO    ][23072] Running state [maas_create_iprange_1] at time 06:14:13.245704
2019-06-04 06:14:13,247 [salt.state       :1813][INFO    ][23072] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-04 06:14:13,305 [salt.state       :300 ][INFO    ][23072] Iprange maas_create_iprange_1 already exist.
2019-06-04 06:14:13,305 [salt.state       :1951][INFO    ][23072] Completed state [maas_create_iprange_1] at time 06:14:13.305439 duration_in_ms=59.734
2019-06-04 06:14:13,306 [salt.state       :1780][INFO    ][23072] Running state [vlan 0] at time 06:14:13.306757
2019-06-04 06:14:13,307 [salt.state       :1813][INFO    ][23072] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-04 06:14:13,375 [salt.loaded.ext.module.maasng:945 ][INFO    ][23072] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-04 06:14:13,488 [salt.loaded.ext.module.maasng:945 ][INFO    ][23072] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-04 06:14:13,691 [salt.loaded.ext.module.maasng:945 ][INFO    ][23072] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'hasksm', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-04 06:14:13,793 [salt.state       :300 ][INFO    ][23072] {'new': 'Vlan untagged was updated'}
2019-06-04 06:14:13,793 [salt.state       :1951][INFO    ][23072] Completed state [vlan 0] at time 06:14:13.793513 duration_in_ms=486.756
2019-06-04 06:14:13,796 [salt.state       :1780][INFO    ][23072] Running state [opnfv] at time 06:14:13.796533
2019-06-04 06:14:13,796 [salt.state       :1813][INFO    ][23072] Executing state maasng.sshkey_present for [opnfv]
2019-06-04 06:14:13,860 [salt.loaded.ext.module.maasng:1903][INFO    ][23072] [{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-06-04 06:14:13,860 [salt.state       :300 ][INFO    ][23072] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-06-04 06:14:13,861 [salt.state       :1951][INFO    ][23072] Completed state [opnfv] at time 06:14:13.861121 duration_in_ms=64.588
2019-06-04 06:14:13,864 [salt.state       :1780][INFO    ][23072] Running state [maas.process_tags] at time 06:14:13.864521
2019-06-04 06:14:13,865 [salt.state       :1813][INFO    ][23072] Executing state module.run for [maas.process_tags]
2019-06-04 06:14:13,865 [salt.utils.decorators:613 ][WARNING ][23072] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 06:14:13,914 [salt.loaded.ext.module.maas:92  ][INFO    ][23072] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-04 06:14:13,968 [salt.state       :300 ][INFO    ][23072] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-04 06:14:13,969 [salt.state       :1951][INFO    ][23072] Completed state [maas.process_tags] at time 06:14:13.969260 duration_in_ms=104.739
2019-06-04 06:14:13,975 [salt.minion      :1711][INFO    ][23072] Returning information for job: 20190604061349506471
2019-06-04 06:14:14,779 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604061414767774
2019-06-04 06:14:14,802 [salt.minion      :1432][INFO    ][23529] Starting a new job with PID 23529
2019-06-04 06:14:21,109 [salt.state       :915 ][INFO    ][23529] Loading fresh modules for state activity
2019-06-04 06:14:21,234 [salt.state       :1780][INFO    ][23529] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:21.234876
2019-06-04 06:14:21,235 [salt.state       :1813][INFO    ][23529] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-04 06:14:21,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23529] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-04 06:14:23,253 [salt.state       :300 ][INFO    ][23529] {'pid': 23554, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-04 06:14:23,254 [salt.state       :1951][INFO    ][23529] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:23.254732 duration_in_ms=2019.855
2019-06-04 06:14:23,259 [salt.state       :1780][INFO    ][23529] Running state [maas.process_machines] at time 06:14:23.259376
2019-06-04 06:14:23,260 [salt.state       :1813][INFO    ][23529] Executing state module.run for [maas.process_machines]
2019-06-04 06:14:23,260 [salt.utils.decorators:613 ][WARNING ][23529] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 06:14:23,837 [salt.loaded.ext.module.maas:412 ][WARNING ][23529] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-04 06:14:23,838 [salt.loaded.ext.module.maas:92  ][INFO    ][23529] 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=nem7xf architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-04 06:14:24,993 [salt.loaded.ext.module.maas:412 ][WARNING ][23529] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-04 06:14:24,994 [salt.loaded.ext.module.maas:92  ][INFO    ][23529] 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=ns6y3x architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-04 06:14:26,152 [salt.loaded.ext.module.maas:412 ][WARNING ][23529] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-04 06:14:26,152 [salt.loaded.ext.module.maas:92  ][INFO    ][23529] 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=dyqwmk architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-04 06:14:27,265 [salt.loaded.ext.module.maas:412 ][WARNING ][23529] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-04 06:14:27,265 [salt.loaded.ext.module.maas:92  ][INFO    ][23529] 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=htt6qt architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-04 06:14:28,503 [salt.loaded.ext.module.maas:412 ][WARNING ][23529] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-04 06:14:28,504 [salt.loaded.ext.module.maas:92  ][INFO    ][23529] 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=fg8fdq architecture=amd64/generic power_parameters_power_user=opnfv
2019-06-04 06:14:29,607 [salt.state       :300 ][INFO    ][23529] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-04 06:14:29,608 [salt.state       :1951][INFO    ][23529] Completed state [maas.process_machines] at time 06:14:29.608015 duration_in_ms=6348.637
2019-06-04 06:14:29,612 [salt.minion      :1711][INFO    ][23529] Returning information for job: 20190604061414767774
2019-06-04 06:15:03,601 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604061503587754
2019-06-04 06:15:03,629 [salt.minion      :1432][INFO    ][23814] Starting a new job with PID 23814
2019-06-04 06:15:09,907 [salt.state       :915 ][INFO    ][23814] Loading fresh modules for state activity
2019-06-04 06:15:10,031 [salt.state       :1780][INFO    ][23814] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:10.030858
2019-06-04 06:15:10,031 [salt.state       :1813][INFO    ][23814] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-04 06:15:10,036 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23814] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-04 06:15:12,000 [salt.state       :300 ][INFO    ][23814] {'pid': 23821, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-04 06:15:12,002 [salt.state       :1951][INFO    ][23814] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:12.002825 duration_in_ms=1971.969
2019-06-04 06:15:12,006 [salt.state       :1780][INFO    ][23814] Running state [maas.wait_for_machine_status] at time 06:15:12.006428
2019-06-04 06:15:12,007 [salt.state       :1813][INFO    ][23814] Executing state module.run for [maas.wait_for_machine_status]
2019-06-04 06:15:12,008 [salt.utils.decorators:613 ][WARNING ][23814] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 06:15:14,986 [salt.state       :300 ][INFO    ][23814] {'ret': True}
2019-06-04 06:15:14,987 [salt.state       :1951][INFO    ][23814] Completed state [maas.wait_for_machine_status] at time 06:15:14.987120 duration_in_ms=2980.691
2019-06-04 06:15:14,993 [salt.minion      :1711][INFO    ][23814] Returning information for job: 20190604061503587754
2019-06-04 06:15:15,706 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604061515693966
2019-06-04 06:15:15,733 [salt.minion      :1432][INFO    ][23853] Starting a new job with PID 23853
2019-06-04 06:15:16,973 [salt.state       :915 ][INFO    ][23853] Loading fresh modules for state activity
2019-06-04 06:15:17,134 [salt.state       :1780][INFO    ][23853] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:17.134798
2019-06-04 06:15:17,135 [salt.state       :1813][INFO    ][23853] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-04 06:15:17,137 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23853] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-04 06:15:19,175 [salt.state       :300 ][INFO    ][23853] {'pid': 23861, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-04 06:15:19,177 [salt.state       :1951][INFO    ][23853] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:19.176916 duration_in_ms=2042.117
2019-06-04 06:15:19,181 [salt.state       :1780][INFO    ][23853] Running state [maas_machines_storage_cmp002_lvm] at time 06:15:19.181746
2019-06-04 06:15:19,182 [salt.state       :1813][INFO    ][23853] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-04 06:15:19,764 [salt.state       :300 ][INFO    ][23853] Machine cmp002 is not in Ready state.
2019-06-04 06:15:19,764 [salt.state       :1951][INFO    ][23853] Completed state [maas_machines_storage_cmp002_lvm] at time 06:15:19.764891 duration_in_ms=583.146
2019-06-04 06:15:19,765 [salt.state       :1780][INFO    ][23853] Running state [maas_machines_storage_cmp001_lvm] at time 06:15:19.765438
2019-06-04 06:15:19,767 [salt.state       :1813][INFO    ][23853] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-04 06:15:20,312 [salt.state       :300 ][INFO    ][23853] Machine cmp001 is not in Ready state.
2019-06-04 06:15:20,312 [salt.state       :1951][INFO    ][23853] Completed state [maas_machines_storage_cmp001_lvm] at time 06:15:20.312838 duration_in_ms=547.4
2019-06-04 06:15:20,320 [salt.minion      :1711][INFO    ][23853] Returning information for job: 20190604061515693966
2019-06-04 06:15:21,055 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604061521041526
2019-06-04 06:15:21,082 [salt.minion      :1432][INFO    ][23872] Starting a new job with PID 23872
2019-06-04 06:15:22,203 [salt.state       :915 ][INFO    ][23872] Loading fresh modules for state activity
2019-06-04 06:15:22,323 [salt.state       :1780][INFO    ][23872] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:22.323560
2019-06-04 06:15:22,324 [salt.state       :1813][INFO    ][23872] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-04 06:15:22,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23872] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-04 06:15:24,329 [salt.state       :300 ][INFO    ][23872] {'pid': 23881, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-04 06:15:24,330 [salt.state       :1951][INFO    ][23872] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:24.330605 duration_in_ms=2007.045
2019-06-04 06:15:24,333 [salt.state       :1780][INFO    ][23872] Running state [maas.deploy_machines] at time 06:15:24.333485
2019-06-04 06:15:24,334 [salt.state       :1813][INFO    ][23872] Executing state module.run for [maas.deploy_machines]
2019-06-04 06:15:24,335 [salt.utils.decorators:613 ][WARNING ][23872] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 06:15:24,917 [salt.loaded.ext.module.maas:684 ][INFO    ][23872] deploymachines hwe_kernel=hwe-16.04 system_id=fg8fdq distro_series=xenial
2019-06-04 06:15:27,358 [salt.state       :300 ][INFO    ][23872] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-06-04 06:15:27,359 [salt.state       :1951][INFO    ][23872] Completed state [maas.deploy_machines] at time 06:15:27.359287 duration_in_ms=3025.802
2019-06-04 06:15:27,363 [salt.minion      :1711][INFO    ][23872] Returning information for job: 20190604061521041526
2019-06-04 06:15:28,112 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command state.apply with jid 20190604061528098293
2019-06-04 06:15:28,138 [salt.minion      :1432][INFO    ][23960] Starting a new job with PID 23960
2019-06-04 06:15:34,674 [salt.state       :915 ][INFO    ][23960] Loading fresh modules for state activity
2019-06-04 06:15:34,768 [salt.state       :1780][INFO    ][23960] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:34.768727
2019-06-04 06:15:34,769 [salt.state       :1813][INFO    ][23960] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-04 06:15:34,770 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23960] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-04 06:15:36,625 [salt.state       :300 ][INFO    ][23960] {'pid': 23993, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-04 06:15:36,626 [salt.state       :1951][INFO    ][23960] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:15:36.626606 duration_in_ms=1857.879
2019-06-04 06:15:36,630 [salt.state       :1780][INFO    ][23960] Running state [maas.wait_for_machine_status] at time 06:15:36.630147
2019-06-04 06:15:36,630 [salt.state       :1813][INFO    ][23960] Executing state module.run for [maas.wait_for_machine_status]
2019-06-04 06:15:36,631 [salt.utils.decorators:613 ][WARNING ][23960] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-04 06:15:39,605 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.04148293s left)
2019-06-04 06:15:43,252 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061543200785
2019-06-04 06:15:43,281 [salt.minion      :1432][INFO    ][24010] Starting a new job with PID 24010
2019-06-04 06:15:43,311 [salt.minion      :1711][INFO    ][24010] Returning information for job: 20190604061543200785
2019-06-04 06:16:12,514 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.13300991s left)
2019-06-04 06:16:13,352 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061613341531
2019-06-04 06:16:13,381 [salt.minion      :1432][INFO    ][24032] Starting a new job with PID 24032
2019-06-04 06:16:13,409 [salt.minion      :1711][INFO    ][24032] Returning information for job: 20190604061613341531
2019-06-04 06:16:43,461 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061643446487
2019-06-04 06:16:43,483 [salt.minion      :1432][INFO    ][24084] Starting a new job with PID 24084
2019-06-04 06:16:43,513 [salt.minion      :1711][INFO    ][24084] Returning information for job: 20190604061643446487
2019-06-04 06:16:45,331 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.31606197s left)
2019-06-04 06:17:13,547 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061713534815
2019-06-04 06:17:13,577 [salt.minion      :1432][INFO    ][24105] Starting a new job with PID 24105
2019-06-04 06:17:13,614 [salt.minion      :1711][INFO    ][24105] Returning information for job: 20190604061713534815
2019-06-04 06:17:18,238 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.40897679s left)
2019-06-04 06:17:43,654 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061743641238
2019-06-04 06:17:43,677 [salt.minion      :1432][INFO    ][24170] Starting a new job with PID 24170
2019-06-04 06:17:43,708 [salt.minion      :1711][INFO    ][24170] Returning information for job: 20190604061743641238
2019-06-04 06:17:51,210 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.43671679s left)
2019-06-04 06:18:13,745 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061813729687
2019-06-04 06:18:13,774 [salt.minion      :1432][INFO    ][24192] Starting a new job with PID 24192
2019-06-04 06:18:13,803 [salt.minion      :1711][INFO    ][24192] Returning information for job: 20190604061813729687
2019-06-04 06:18:24,000 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.64697385s left)
2019-06-04 06:18:43,849 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061843831819
2019-06-04 06:18:43,877 [salt.minion      :1432][INFO    ][24250] Starting a new job with PID 24250
2019-06-04 06:18:43,911 [salt.minion      :1711][INFO    ][24250] Returning information for job: 20190604061843831819
2019-06-04 06:18:56,856 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2049.79053593s left)
2019-06-04 06:19:13,940 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061913926106
2019-06-04 06:19:13,964 [salt.minion      :1432][INFO    ][24270] Starting a new job with PID 24270
2019-06-04 06:19:13,995 [salt.minion      :1711][INFO    ][24270] Returning information for job: 20190604061913926106
2019-06-04 06:19:29,855 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2016.79137492s left)
2019-06-04 06:19:44,051 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604061944037228
2019-06-04 06:19:44,073 [salt.minion      :1432][INFO    ][24350] Starting a new job with PID 24350
2019-06-04 06:19:44,108 [salt.minion      :1711][INFO    ][24350] Returning information for job: 20190604061944037228
2019-06-04 06:20:02,686 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1983.96079493s left)
2019-06-04 06:20:14,165 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062014154204
2019-06-04 06:20:14,189 [salt.minion      :1432][INFO    ][24385] Starting a new job with PID 24385
2019-06-04 06:20:14,223 [salt.minion      :1711][INFO    ][24385] Returning information for job: 20190604062014154204
2019-06-04 06:20:35,583 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.06329489s left)
2019-06-04 06:20:44,309 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062044293049
2019-06-04 06:20:44,337 [salt.minion      :1432][INFO    ][24532] Starting a new job with PID 24532
2019-06-04 06:20:44,370 [salt.minion      :1711][INFO    ][24532] Returning information for job: 20190604062044293049
2019-06-04 06:21:08,730 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1917.91638088s left)
2019-06-04 06:21:14,427 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062114413255
2019-06-04 06:21:14,448 [salt.minion      :1432][INFO    ][24559] Starting a new job with PID 24559
2019-06-04 06:21:14,486 [salt.minion      :1711][INFO    ][24559] Returning information for job: 20190604062114413255
2019-06-04 06:21:41,727 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1884.91908193s left)
2019-06-04 06:21:44,585 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062144570808
2019-06-04 06:21:44,608 [salt.minion      :1432][INFO    ][24674] Starting a new job with PID 24674
2019-06-04 06:21:44,636 [salt.minion      :1711][INFO    ][24674] Returning information for job: 20190604062144570808
2019-06-04 06:22:14,623 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.02332187s left)
2019-06-04 06:22:14,715 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062214700355
2019-06-04 06:22:14,741 [salt.minion      :1432][INFO    ][24705] Starting a new job with PID 24705
2019-06-04 06:22:14,775 [salt.minion      :1711][INFO    ][24705] Returning information for job: 20190604062214700355
2019-06-04 06:22:44,867 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062244860303
2019-06-04 06:22:44,888 [salt.minion      :1432][INFO    ][24793] Starting a new job with PID 24793
2019-06-04 06:22:44,921 [salt.minion      :1711][INFO    ][24793] Returning information for job: 20190604062244860303
2019-06-04 06:22:47,627 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1819.01978397s left)
2019-06-04 06:23:15,008 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062314999679
2019-06-04 06:23:15,031 [salt.minion      :1432][INFO    ][24826] Starting a new job with PID 24826
2019-06-04 06:23:15,063 [salt.minion      :1711][INFO    ][24826] Returning information for job: 20190604062314999679
2019-06-04 06:23:20,566 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1786.08074093s left)
2019-06-04 06:23:45,181 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062345164693
2019-06-04 06:23:45,212 [salt.minion      :1432][INFO    ][25021] Starting a new job with PID 25021
2019-06-04 06:23:45,247 [salt.minion      :1711][INFO    ][25021] Returning information for job: 20190604062345164693
2019-06-04 06:23:53,568 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1753.07827187s left)
2019-06-04 06:24:15,375 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062415362379
2019-06-04 06:24:15,402 [salt.minion      :1432][INFO    ][25052] Starting a new job with PID 25052
2019-06-04 06:24:15,432 [salt.minion      :1711][INFO    ][25052] Returning information for job: 20190604062415362379
2019-06-04 06:24:26,528 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1720.11861181s left)
2019-06-04 06:24:45,558 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062445546459
2019-06-04 06:24:45,585 [salt.minion      :1432][INFO    ][25098] Starting a new job with PID 25098
2019-06-04 06:24:45,616 [salt.minion      :1711][INFO    ][25098] Returning information for job: 20190604062445546459
2019-06-04 06:24:59,715 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1686.93121386s left)
2019-06-04 06:25:15,756 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062515740707
2019-06-04 06:25:15,786 [salt.minion      :1432][INFO    ][25205] Starting a new job with PID 25205
2019-06-04 06:25:15,816 [salt.minion      :1711][INFO    ][25205] Returning information for job: 20190604062515740707
2019-06-04 06:25:32,626 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.02032089s left)
2019-06-04 06:25:45,932 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062545922972
2019-06-04 06:25:45,960 [salt.minion      :1432][INFO    ][25269] Starting a new job with PID 25269
2019-06-04 06:25:45,990 [salt.minion      :1711][INFO    ][25269] Returning information for job: 20190604062545922972
2019-06-04 06:26:05,440 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.20671082s left)
2019-06-04 06:26:16,123 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062616106971
2019-06-04 06:26:16,153 [salt.minion      :1432][INFO    ][25298] Starting a new job with PID 25298
2019-06-04 06:26:16,188 [salt.minion      :1711][INFO    ][25298] Returning information for job: 20190604062616106971
2019-06-04 06:26:38,440 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.20684099s left)
2019-06-04 06:26:46,301 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062646288570
2019-06-04 06:26:46,330 [salt.minion      :1432][INFO    ][25347] Starting a new job with PID 25347
2019-06-04 06:26:46,364 [salt.minion      :1711][INFO    ][25347] Returning information for job: 20190604062646288570
2019-06-04 06:27:11,394 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.2532959s left)
2019-06-04 06:27:16,515 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062716500328
2019-06-04 06:27:16,540 [salt.minion      :1432][INFO    ][25379] Starting a new job with PID 25379
2019-06-04 06:27:16,571 [salt.minion      :1711][INFO    ][25379] Returning information for job: 20190604062716500328
2019-06-04 06:27:44,593 [salt.loaded.ext.module.maas:1023][INFO    ][23960] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1522.05384398s left)
2019-06-04 06:27:46,717 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062746704329
2019-06-04 06:27:46,745 [salt.minion      :1432][INFO    ][25460] Starting a new job with PID 25460
2019-06-04 06:27:46,783 [salt.minion      :1711][INFO    ][25460] Returning information for job: 20190604062746704329
2019-06-04 06:28:16,770 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command saltutil.find_job with jid 20190604062816757269
2019-06-04 06:28:16,791 [salt.minion      :1432][INFO    ][25514] Starting a new job with PID 25514
2019-06-04 06:28:16,828 [salt.minion      :1711][INFO    ][25514] Returning information for job: 20190604062816757269
2019-06-04 06:28:17,659 [salt.state       :300 ][INFO    ][23960] {'ret': True}
2019-06-04 06:28:17,660 [salt.state       :1951][INFO    ][23960] Completed state [maas.wait_for_machine_status] at time 06:28:17.659944 duration_in_ms=761029.791
2019-06-04 06:28:17,670 [salt.minion      :1711][INFO    ][23960] Returning information for job: 20190604061528098293
2019-06-04 07:05:20,536 [salt.utils.schedule:1377][INFO    ][3035] Running scheduled job: __mine_interval
2019-06-04 07:54:52,858 [salt.minion      :1308][INFO    ][3035] User sudo_ubuntu Executing command cp.push_dir with jid 20190604075452845376
2019-06-04 07:54:52,890 [salt.minion      :1432][INFO    ][31547] Starting a new job with PID 31547
