2019-03-23 05:13:41,808 [salt.utils.decorators:613 ][WARNING ][2034] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:13:42,388 [salt.utils.decorators:613 ][WARNING ][2034] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:13:44,376 [salt.loaded.int.states.file:2298][WARNING ][2477] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-03-23 05:14:08,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3233] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-03-23 05:14:08,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3233] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-03-23 05:14:08,751 [salt.utils.parsers:1051][WARNING ][363] Minion received a SIGTERM. Exiting.
2019-03-23 05:14:09,728 [salt.cli.daemons :293 ][INFO    ][3350] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-03-23 05:14:09,844 [salt.cli.daemons :82  ][INFO    ][3350] Starting up the Salt Minion
2019-03-23 05:14:09,845 [salt.utils.event :1017][INFO    ][3350] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-03-23 05:14:10,582 [salt.minion      :976 ][INFO    ][3350] Creating minion process manager
2019-03-23 05:14:11,677 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3350] Executing command ['date', '+%z'] in directory '/root'
2019-03-23 05:14:11,691 [salt.utils.schedule:568 ][INFO    ][3350] Updating job settings for scheduled job: __mine_interval
2019-03-23 05:14:11,760 [salt.minion      :1108][INFO    ][3350] Added mine.update to scheduler
2019-03-23 05:14:11,766 [salt.minion      :1975][INFO    ][3350] Minion is starting as user 'root'
2019-03-23 05:14:11,783 [salt.minion      :2336][INFO    ][3350] Minion is ready to receive requests!
2019-03-23 05:14:13,950 [salt.state       :2022][WARNING ][3238] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-23 05:14:16,516 [salt.utils.decorators:613 ][WARNING ][3238] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:14:22,419 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051422413529
2019-03-23 05:14:22,431 [salt.minion      :1432][INFO    ][3810] Starting a new job with PID 3810
2019-03-23 05:14:22,444 [salt.minion      :1711][INFO    ][3810] Returning information for job: 20190323051422413529
2019-03-23 05:14:41,890 [salt.utils.decorators:613 ][WARNING ][3238] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:14:52,459 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051452443213
2019-03-23 05:14:52,480 [salt.minion      :1432][INFO    ][4159] Starting a new job with PID 4159
2019-03-23 05:14:52,504 [salt.minion      :1711][INFO    ][4159] Returning information for job: 20190323051452443213
2019-03-23 05:15:13,781 [salt.utils.decorators:613 ][WARNING ][3238] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:15:22,522 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051522506763
2019-03-23 05:15:22,544 [salt.minion      :1432][INFO    ][4276] Starting a new job with PID 4276
2019-03-23 05:15:22,567 [salt.minion      :1711][INFO    ][4276] Returning information for job: 20190323051522506763
2019-03-23 05:15:52,573 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051552560071
2019-03-23 05:15:52,595 [salt.minion      :1432][INFO    ][4312] Starting a new job with PID 4312
2019-03-23 05:15:52,618 [salt.minion      :1711][INFO    ][4312] Returning information for job: 20190323051552560071
2019-03-23 05:16:22,632 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051622616500
2019-03-23 05:16:22,654 [salt.minion      :1432][INFO    ][4358] Starting a new job with PID 4358
2019-03-23 05:16:22,678 [salt.minion      :1711][INFO    ][4358] Returning information for job: 20190323051622616500
2019-03-23 05:16:52,686 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051652673769
2019-03-23 05:16:52,708 [salt.minion      :1432][INFO    ][4400] Starting a new job with PID 4400
2019-03-23 05:16:52,731 [salt.minion      :1711][INFO    ][4400] Returning information for job: 20190323051652673769
2019-03-23 05:17:22,748 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051722731717
2019-03-23 05:17:22,767 [salt.minion      :1432][INFO    ][4452] Starting a new job with PID 4452
2019-03-23 05:17:22,787 [salt.minion      :1711][INFO    ][4452] Returning information for job: 20190323051722731717
2019-03-23 05:17:52,805 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051752789133
2019-03-23 05:17:52,827 [salt.minion      :1432][INFO    ][4497] Starting a new job with PID 4497
2019-03-23 05:17:52,851 [salt.minion      :1711][INFO    ][4497] Returning information for job: 20190323051752789133
2019-03-23 05:18:22,877 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051822861894
2019-03-23 05:18:22,899 [salt.minion      :1432][INFO    ][4545] Starting a new job with PID 4545
2019-03-23 05:18:22,921 [salt.minion      :1711][INFO    ][4545] Returning information for job: 20190323051822861894
2019-03-23 05:18:52,942 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051852929250
2019-03-23 05:18:52,964 [salt.minion      :1432][INFO    ][4588] Starting a new job with PID 4588
2019-03-23 05:18:52,986 [salt.minion      :1711][INFO    ][4588] Returning information for job: 20190323051852929250
2019-03-23 05:19:23,012 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051922998726
2019-03-23 05:19:23,034 [salt.minion      :1432][INFO    ][4637] Starting a new job with PID 4637
2019-03-23 05:19:23,057 [salt.minion      :1711][INFO    ][4637] Returning information for job: 20190323051922998726
2019-03-23 05:19:53,089 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323051953075807
2019-03-23 05:19:53,111 [salt.minion      :1432][INFO    ][4678] Starting a new job with PID 4678
2019-03-23 05:19:53,134 [salt.minion      :1711][INFO    ][4678] Returning information for job: 20190323051953075807
2019-03-23 05:20:23,174 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052023160806
2019-03-23 05:20:23,196 [salt.minion      :1432][INFO    ][4727] Starting a new job with PID 4727
2019-03-23 05:20:23,219 [salt.minion      :1711][INFO    ][4727] Returning information for job: 20190323052023160806
2019-03-23 05:20:53,260 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052053247873
2019-03-23 05:20:53,282 [salt.minion      :1432][INFO    ][4772] Starting a new job with PID 4772
2019-03-23 05:20:53,306 [salt.minion      :1711][INFO    ][4772] Returning information for job: 20190323052053247873
2019-03-23 05:21:23,359 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052123346968
2019-03-23 05:21:23,381 [salt.minion      :1432][INFO    ][4821] Starting a new job with PID 4821
2019-03-23 05:21:23,404 [salt.minion      :1711][INFO    ][4821] Returning information for job: 20190323052123346968
2019-03-23 05:21:53,460 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052153447144
2019-03-23 05:21:53,481 [salt.minion      :1432][INFO    ][4862] Starting a new job with PID 4862
2019-03-23 05:21:53,506 [salt.minion      :1711][INFO    ][4862] Returning information for job: 20190323052153447144
2019-03-23 05:22:23,568 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052223555215
2019-03-23 05:22:23,590 [salt.minion      :1432][INFO    ][4913] Starting a new job with PID 4913
2019-03-23 05:22:23,615 [salt.minion      :1711][INFO    ][4913] Returning information for job: 20190323052223555215
2019-03-23 05:22:53,681 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052253668205
2019-03-23 05:22:53,703 [salt.minion      :1432][INFO    ][4954] Starting a new job with PID 4954
2019-03-23 05:22:53,728 [salt.minion      :1711][INFO    ][4954] Returning information for job: 20190323052253668205
2019-03-23 05:23:23,803 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052323790896
2019-03-23 05:23:23,824 [salt.minion      :1432][INFO    ][5002] Starting a new job with PID 5002
2019-03-23 05:23:23,848 [salt.minion      :1711][INFO    ][5002] Returning information for job: 20190323052323790896
2019-03-23 05:23:53,928 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052353914787
2019-03-23 05:23:53,950 [salt.minion      :1432][INFO    ][5046] Starting a new job with PID 5046
2019-03-23 05:23:53,973 [salt.minion      :1711][INFO    ][5046] Returning information for job: 20190323052353914787
2019-03-23 05:24:24,062 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052424049062
2019-03-23 05:24:24,083 [salt.minion      :1432][INFO    ][5108] Starting a new job with PID 5108
2019-03-23 05:24:24,107 [salt.minion      :1711][INFO    ][5108] Returning information for job: 20190323052424049062
2019-03-23 05:24:54,201 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052454188628
2019-03-23 05:24:54,224 [salt.minion      :1432][INFO    ][5159] Starting a new job with PID 5159
2019-03-23 05:24:54,246 [salt.minion      :1711][INFO    ][5159] Returning information for job: 20190323052454188628
2019-03-23 05:25:24,352 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052524339683
2019-03-23 05:25:24,373 [salt.minion      :1432][INFO    ][5210] Starting a new job with PID 5210
2019-03-23 05:25:24,395 [salt.minion      :1711][INFO    ][5210] Returning information for job: 20190323052524339683
2019-03-23 05:25:54,502 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052554494606
2019-03-23 05:25:54,522 [salt.minion      :1432][INFO    ][5250] Starting a new job with PID 5250
2019-03-23 05:25:54,544 [salt.minion      :1711][INFO    ][5250] Returning information for job: 20190323052554494606
2019-03-23 05:26:24,667 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052624654733
2019-03-23 05:26:24,690 [salt.minion      :1432][INFO    ][5299] Starting a new job with PID 5299
2019-03-23 05:26:24,712 [salt.minion      :1711][INFO    ][5299] Returning information for job: 20190323052624654733
2019-03-23 05:26:54,844 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052654831087
2019-03-23 05:26:54,867 [salt.minion      :1432][INFO    ][5341] Starting a new job with PID 5341
2019-03-23 05:26:54,892 [salt.minion      :1711][INFO    ][5341] Returning information for job: 20190323052654831087
2019-03-23 05:27:25,032 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052725020186
2019-03-23 05:27:25,054 [salt.minion      :1432][INFO    ][5389] Starting a new job with PID 5389
2019-03-23 05:27:25,077 [salt.minion      :1711][INFO    ][5389] Returning information for job: 20190323052725020186
2019-03-23 05:27:55,227 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052755214861
2019-03-23 05:27:55,249 [salt.minion      :1432][INFO    ][5431] Starting a new job with PID 5431
2019-03-23 05:27:55,273 [salt.minion      :1711][INFO    ][5431] Returning information for job: 20190323052755214861
2019-03-23 05:28:25,432 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052825419342
2019-03-23 05:28:25,455 [salt.minion      :1432][INFO    ][5480] Starting a new job with PID 5480
2019-03-23 05:28:25,479 [salt.minion      :1711][INFO    ][5480] Returning information for job: 20190323052825419342
2019-03-23 05:28:55,655 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052855641721
2019-03-23 05:28:55,677 [salt.minion      :1432][INFO    ][5523] Starting a new job with PID 5523
2019-03-23 05:28:55,702 [salt.minion      :1711][INFO    ][5523] Returning information for job: 20190323052855641721
2019-03-23 05:29:25,884 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052925870753
2019-03-23 05:29:25,907 [salt.minion      :1432][INFO    ][5570] Starting a new job with PID 5570
2019-03-23 05:29:25,930 [salt.minion      :1711][INFO    ][5570] Returning information for job: 20190323052925870753
2019-03-23 05:29:55,917 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323052955904119
2019-03-23 05:29:55,939 [salt.minion      :1432][INFO    ][5615] Starting a new job with PID 5615
2019-03-23 05:29:55,961 [salt.minion      :1711][INFO    ][5615] Returning information for job: 20190323052955904119
2019-03-23 05:30:14,500 [salt.utils.decorators:613 ][WARNING ][3238] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:30:17,638 [salt.loaded.ext.module.maas:160 ][ERROR   ][3238] Failed for object commissioning_distro_series reason {"commissioning_distro_series": ["'xenial' is not a valid commissioning_distro_series.  It should be one of: '---'."]}
2019-03-23 05:30:17,744 [salt.loaded.ext.module.maas:160 ][ERROR   ][3238] Failed for object default_osystem reason {"default_osystem": ["'ubuntu' is not a valid osystem.  It should be one of: '---'."]}
2019-03-23 05:30:18,354 [salt.loaded.ext.module.maas:160 ][ERROR   ][3238] Failed for object default_distro_series reason {"default_distro_series": ["'xenial' is not a valid release.  It should be one of: '---'."]}
2019-03-23 05:30:18,462 [salt.state       :302 ][ERROR   ][3238] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'commissioning_distro_series': '{"commissioning_distro_series": ["\'xenial\' is not a valid commissioning_distro_series.  It should be one of: \'---\'."]}', 'default_osystem': '{"default_osystem": ["\'ubuntu\' is not a valid osystem.  It should be one of: \'---\'."]}', 'default_distro_series': '{"default_distro_series": ["\'xenial\' is not a valid release.  It should be one of: \'---\'."]}'}, 'success': ['enable_http_proxy', 'upstream_dns', '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_min_hwe_kernel']}
2019-03-23 05:30:18,592 [salt.loaded.ext.module.maasng:1008][WARNING ][3238] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-03-23 05:30:18,593 [salt.loaded.ext.module.maasng:1011][WARNING ][3238] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-03-23 05:30:19,450 [salt.loaded.ext.module.maasng:1235][WARNING ][3238] Ignoring parameter vlan:0
2019-03-23 05:30:20,633 [salt.utils.decorators:613 ][WARNING ][3238] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:30:31,455 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command state.apply with jid 20190323053031441362
2019-03-23 05:30:31,478 [salt.minion      :1432][INFO    ][5767] Starting a new job with PID 5767
2019-03-23 05:30:35,033 [salt.state       :915 ][INFO    ][5767] Loading fresh modules for state activity
2019-03-23 05:30:35,692 [salt.state       :1780][INFO    ][5767] Running state [maas-region-controller] at time 05:30:35.692129
2019-03-23 05:30:35,692 [salt.state       :1813][INFO    ][5767] Executing state pkg.installed for [maas-region-controller]
2019-03-23 05:30:35,692 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-03-23 05:30:35,784 [salt.state       :300 ][INFO    ][5767] All specified packages are already installed
2019-03-23 05:30:35,784 [salt.state       :1951][INFO    ][5767] Completed state [maas-region-controller] at time 05:30:35.784638 duration_in_ms=92.509
2019-03-23 05:30:35,785 [salt.state       :1780][INFO    ][5767] Running state [python-oauth] at time 05:30:35.784997
2019-03-23 05:30:35,785 [salt.state       :1813][INFO    ][5767] Executing state pkg.installed for [python-oauth]
2019-03-23 05:30:35,793 [salt.state       :300 ][INFO    ][5767] All specified packages are already installed
2019-03-23 05:30:35,794 [salt.state       :1951][INFO    ][5767] Completed state [python-oauth] at time 05:30:35.794090 duration_in_ms=9.092
2019-03-23 05:30:35,797 [salt.state       :1780][INFO    ][5767] Running state [/etc/maas/regiond.conf] at time 05:30:35.797259
2019-03-23 05:30:35,797 [salt.state       :1813][INFO    ][5767] Executing state file.replace for [/etc/maas/regiond.conf]
2019-03-23 05:30:35,828 [salt.state       :300 ][INFO    ][5767] No changes needed to be made
2019-03-23 05:30:35,829 [salt.state       :1951][INFO    ][5767] Completed state [/etc/maas/regiond.conf] at time 05:30:35.828987 duration_in_ms=31.728
2019-03-23 05:30:35,829 [salt.state       :1780][INFO    ][5767] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:30:35.829430
2019-03-23 05:30:35,829 [salt.state       :1813][INFO    ][5767] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-03-23 05:30:35,894 [salt.state       :300 ][INFO    ][5767] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-03-23 05:30:35,894 [salt.state       :1951][INFO    ][5767] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:30:35.894389 duration_in_ms=64.959
2019-03-23 05:30:35,894 [salt.state       :1780][INFO    ][5767] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:30:35.894857
2019-03-23 05:30:35,895 [salt.state       :1813][INFO    ][5767] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-23 05:30:35,950 [salt.state       :300 ][INFO    ][5767] No changes needed to be made
2019-03-23 05:30:35,951 [salt.state       :1951][INFO    ][5767] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:30:35.951252 duration_in_ms=56.394
2019-03-23 05:30:35,952 [salt.state       :1780][INFO    ][5767] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:30:35.952064
2019-03-23 05:30:35,952 [salt.state       :1813][INFO    ][5767] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-03-23 05:30:36,028 [salt.state       :300 ][INFO    ][5767] No changes needed to be made
2019-03-23 05:30:36,029 [salt.state       :1951][INFO    ][5767] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:30:36.029042 duration_in_ms=76.978
2019-03-23 05:30:36,029 [salt.state       :1780][INFO    ][5767] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:30:36.029879
2019-03-23 05:30:36,030 [salt.state       :1813][INFO    ][5767] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-03-23 05:30:36,067 [salt.state       :300 ][INFO    ][5767] No changes needed to be made
2019-03-23 05:30:36,067 [salt.state       :1951][INFO    ][5767] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:30:36.067452 duration_in_ms=37.573
2019-03-23 05:30:36,068 [salt.state       :1780][INFO    ][5767] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:30:36.068132
2019-03-23 05:30:36,068 [salt.state       :1813][INFO    ][5767] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-03-23 05:30:36,082 [salt.state       :300 ][INFO    ][5767] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-03-23 05:30:36,082 [salt.state       :1951][INFO    ][5767] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:30:36.082472 duration_in_ms=14.34
2019-03-23 05:30:36,084 [salt.state       :1780][INFO    ][5767] Running state [a2enmod headers] at time 05:30:36.084098
2019-03-23 05:30:36,084 [salt.state       :1813][INFO    ][5767] Executing state cmd.run for [a2enmod headers]
2019-03-23 05:30:36,085 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command 'a2enmod headers' in directory '/root'
2019-03-23 05:30:36,159 [salt.state       :300 ][INFO    ][5767] {'pid': 5786, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-03-23 05:30:36,160 [salt.state       :1951][INFO    ][5767] Completed state [a2enmod headers] at time 05:30:36.160245 duration_in_ms=76.146
2019-03-23 05:30:36,160 [salt.state       :1780][INFO    ][5767] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:30:36.160826
2019-03-23 05:30:36,161 [salt.state       :1813][INFO    ][5767] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-03-23 05:30:36,179 [salt.state       :300 ][INFO    ][5767] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-03-23 05:30:36,180 [salt.state       :1951][INFO    ][5767] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:30:36.180125 duration_in_ms=19.299
2019-03-23 05:30:36,181 [salt.state       :1780][INFO    ][5767] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:30:36.180994
2019-03-23 05:30:36,181 [salt.state       :1813][INFO    ][5767] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-03-23 05:30:36,308 [salt.state       :300 ][INFO    ][5767] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-03-23 05:30:36,309 [salt.state       :1951][INFO    ][5767] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:30:36.309160 duration_in_ms=128.164
2019-03-23 05:30:36,310 [salt.state       :1780][INFO    ][5767] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:30:36.310290
2019-03-23 05:30:36,310 [salt.state       :1813][INFO    ][5767] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-03-23 05:30:36,424 [salt.state       :300 ][INFO    ][5767] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-03-23 05:30:36,425 [salt.state       :1951][INFO    ][5767] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:30:36.425342 duration_in_ms=115.05
2019-03-23 05:30:36,426 [salt.state       :1780][INFO    ][5767] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:30:36.426259
2019-03-23 05:30:36,426 [salt.state       :1813][INFO    ][5767] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-03-23 05:30:36,538 [salt.state       :300 ][INFO    ][5767] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-03-23 05:30:36,539 [salt.state       :1951][INFO    ][5767] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:30:36.539197 duration_in_ms=112.938
2019-03-23 05:30:36,539 [salt.state       :1780][INFO    ][5767] Running state [/root/.pgpass] at time 05:30:36.539687
2019-03-23 05:30:36,540 [salt.state       :1813][INFO    ][5767] Executing state file.managed for [/root/.pgpass]
2019-03-23 05:30:36,634 [salt.state       :300 ][INFO    ][5767] File /root/.pgpass is in the correct state
2019-03-23 05:30:36,635 [salt.state       :1951][INFO    ][5767] Completed state [/root/.pgpass] at time 05:30:36.635053 duration_in_ms=95.366
2019-03-23 05:30:36,644 [salt.state       :1780][INFO    ][5767] Running state [maas-region syncdb --noinput] at time 05:30:36.643983
2019-03-23 05:30:36,644 [salt.state       :1813][INFO    ][5767] Executing state cmd.run for [maas-region syncdb --noinput]
2019-03-23 05:30:36,645 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-03-23 05:30:38,600 [salt.state       :300 ][INFO    ][5767] {'pid': 5799, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, sites, auth, contenttypes, maasserver, piston3, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-03-23 05:30:38,601 [salt.state       :1951][INFO    ][5767] Completed state [maas-region syncdb --noinput] at time 05:30:38.600914 duration_in_ms=1956.931
2019-03-23 05:30:38,601 [salt.state       :2022][WARNING ][5767] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-03-23 05:30:38,603 [salt.state       :1780][INFO    ][5767] Running state [maas-regiond] at time 05:30:38.603907
2019-03-23 05:30:38,604 [salt.state       :1813][INFO    ][5767] Executing state service.running for [maas-regiond]
2019-03-23 05:30:38,605 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-03-23 05:30:38,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-03-23 05:30:38,660 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-03-23 05:30:38,677 [salt.state       :300 ][INFO    ][5767] The service maas-regiond is already running
2019-03-23 05:30:38,678 [salt.state       :1951][INFO    ][5767] Completed state [maas-regiond] at time 05:30:38.678064 duration_in_ms=74.157
2019-03-23 05:30:38,680 [salt.state       :1780][INFO    ][5767] Running state [bind9] at time 05:30:38.680506
2019-03-23 05:30:38,680 [salt.state       :1813][INFO    ][5767] Executing state service.running for [bind9]
2019-03-23 05:30:38,681 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-03-23 05:30:38,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-03-23 05:30:38,719 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-03-23 05:30:38,737 [salt.state       :300 ][INFO    ][5767] The service bind9 is already running
2019-03-23 05:30:38,737 [salt.state       :1951][INFO    ][5767] Completed state [bind9] at time 05:30:38.737679 duration_in_ms=57.173
2019-03-23 05:30:38,740 [salt.state       :1780][INFO    ][5767] Running state [apache2] at time 05:30:38.739986
2019-03-23 05:30:38,740 [salt.state       :1813][INFO    ][5767] Executing state service.running for [apache2]
2019-03-23 05:30:38,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-03-23 05:30:38,759 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-03-23 05:30:38,776 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-03-23 05:30:38,798 [salt.state       :300 ][INFO    ][5767] The service apache2 is already running
2019-03-23 05:30:38,798 [salt.state       :1951][INFO    ][5767] Completed state [apache2] at time 05:30:38.798849 duration_in_ms=58.863
2019-03-23 05:30:38,801 [salt.state       :1780][INFO    ][5767] Running state [maasng.wait_for_http_code] at time 05:30:38.800966
2019-03-23 05:30:38,801 [salt.state       :1813][INFO    ][5767] Executing state module.run for [maasng.wait_for_http_code]
2019-03-23 05:30:38,802 [salt.utils.decorators:613 ][WARNING ][5767] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:30:39,021 [salt.state       :300 ][INFO    ][5767] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-03-23 05:30:39,022 [salt.state       :1951][INFO    ][5767] Completed state [maasng.wait_for_http_code] at time 05:30:39.022060 duration_in_ms=221.094
2019-03-23 05:30:39,023 [salt.state       :1780][INFO    ][5767] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:30:39.023438
2019-03-23 05:30:39,024 [salt.state       :1813][INFO    ][5767] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-03-23 05:30:39,024 [salt.state       :300 ][INFO    ][5767] /var/lib/maas/.setup_admin exists
2019-03-23 05:30:39,025 [salt.state       :1951][INFO    ][5767] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:30:39.025005 duration_in_ms=1.568
2019-03-23 05:30:39,026 [salt.state       :1780][INFO    ][5767] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:39.026078
2019-03-23 05:30:39,026 [salt.state       :1813][INFO    ][5767] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-23 05:30:39,027 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5767] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-23 05:30:40,509 [salt.state       :300 ][INFO    ][5767] {'pid': 5824, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-23 05:30:40,509 [salt.state       :1951][INFO    ][5767] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:40.509769 duration_in_ms=1483.692
2019-03-23 05:30:40,518 [salt.state       :1780][INFO    ][5767] Running state [maas_region_boot_source_resources_mirror] at time 05:30:40.518537
2019-03-23 05:30:40,519 [salt.state       :1813][INFO    ][5767] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-03-23 05:30:40,618 [salt.state       :300 ][INFO    ][5767] {'changes': {}}
2019-03-23 05:30:40,619 [salt.state       :1951][INFO    ][5767] Completed state [maas_region_boot_source_resources_mirror] at time 05:30:40.619061 duration_in_ms=100.523
2019-03-23 05:30:40,620 [salt.state       :1780][INFO    ][5767] Running state [maasng.boot_resources_import] at time 05:30:40.620108
2019-03-23 05:30:40,620 [salt.state       :1813][INFO    ][5767] Executing state module.run for [maasng.boot_resources_import]
2019-03-23 05:30:40,621 [salt.utils.decorators:613 ][WARNING ][5767] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:30:40,771 [salt.loaded.ext.module.maasng:1600][INFO    ][5767] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-03-23 05:30:45,835 [salt.loaded.ext.module.maasng:1600][INFO    ][5767] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-03-23 05:30:46,545 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053046533138
2019-03-23 05:30:46,568 [salt.minion      :1432][INFO    ][5866] Starting a new job with PID 5866
2019-03-23 05:30:46,593 [salt.minion      :1711][INFO    ][5866] Returning information for job: 20190323053046533138
2019-03-23 05:30:50,902 [salt.loaded.ext.module.maasng:1600][INFO    ][5767] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-03-23 05:30:55,990 [salt.state       :300 ][INFO    ][5767] {'ret': True}
2019-03-23 05:30:55,991 [salt.state       :1951][INFO    ][5767] Completed state [maasng.boot_resources_import] at time 05:30:55.991344 duration_in_ms=15371.236
2019-03-23 05:30:55,992 [salt.state       :1780][INFO    ][5767] Running state [maas_region_boot_sources_selection_xenial] at time 05:30:55.992564
2019-03-23 05:30:55,993 [salt.state       :1813][INFO    ][5767] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-03-23 05:30:56,158 [salt.state       :300 ][INFO    ][5767] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-03-23 05:30:56,158 [salt.state       :1951][INFO    ][5767] Completed state [maas_region_boot_sources_selection_xenial] at time 05:30:56.158837 duration_in_ms=166.272
2019-03-23 05:30:56,159 [salt.state       :1780][INFO    ][5767] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:30:56.159915
2019-03-23 05:30:56,160 [salt.state       :1813][INFO    ][5767] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-03-23 05:30:56,160 [salt.utils.decorators:613 ][WARNING ][5767] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:30:56,161 [salt.loaded.ext.module.maasng:1771][INFO    ][5767] boot-sources sync initiated for ALL Rack's
2019-03-23 05:30:56,541 [salt.loaded.ext.module.maasng:1878][INFO    ][5767] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-03-23 05:31:01,714 [salt.loaded.ext.module.maasng:1878][INFO    ][5767] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-03-23 05:31:06,984 [salt.loaded.ext.module.maasng:1878][INFO    ][5767] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-03-23 05:31:12,835 [salt.state       :300 ][INFO    ][5767] {'ret': True}
2019-03-23 05:31:12,836 [salt.state       :1951][INFO    ][5767] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:31:12.836123 duration_in_ms=16676.207
2019-03-23 05:31:12,838 [salt.state       :1780][INFO    ][5767] Running state [maas.process_maas_config] at time 05:31:12.838291
2019-03-23 05:31:12,838 [salt.state       :1813][INFO    ][5767] Executing state module.run for [maas.process_maas_config]
2019-03-23 05:31:12,839 [salt.utils.decorators:613 ][WARNING ][5767] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:31:12,840 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=enable_http_proxy value=True
2019-03-23 05:31:12,903 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=upstream_dns value=8.8.8.8
2019-03-23 05:31:12,974 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=commissioning_distro_series value=xenial
2019-03-23 05:31:13,051 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=default_osystem value=ubuntu
2019-03-23 05:31:13,111 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=active_discovery_interval value=600
2019-03-23 05:31:16,072 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=dnssec_validation value=no
2019-03-23 05:31:16,122 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=maas_name value=mas01
2019-03-23 05:31:16,178 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=network_discovery value=enabled
2019-03-23 05:31:16,334 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=enable_third_party_drivers value=True
2019-03-23 05:31:16,380 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=default_storage_layout value=lvm
2019-03-23 05:31:16,409 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=ntp_external_only value=True
2019-03-23 05:31:16,446 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=disk_erase_with_secure_erase value=False
2019-03-23 05:31:16,489 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=default_distro_series value=xenial
2019-03-23 05:31:16,536 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-03-23 05:31:16,589 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053116578855
2019-03-23 05:31:16,607 [salt.minion      :1432][INFO    ][6099] Starting a new job with PID 6099
2019-03-23 05:31:16,614 [salt.state       :300 ][INFO    ][5767] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-03-23 05:31:16,615 [salt.state       :1951][INFO    ][5767] Completed state [maas.process_maas_config] at time 05:31:16.615245 duration_in_ms=3776.954
2019-03-23 05:31:16,616 [salt.state       :1780][INFO    ][5767] Running state [pxe_admin] at time 05:31:16.616085
2019-03-23 05:31:16,616 [salt.state       :1813][INFO    ][5767] Executing state maasng.fabric_present for [pxe_admin]
2019-03-23 05:31:16,633 [salt.minion      :1711][INFO    ][6099] Returning information for job: 20190323053116578855
2019-03-23 05:31:16,667 [salt.loaded.ext.module.maasng:945 ][INFO    ][5767] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': u'qsd8ah', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-03-23 05:31:16,714 [salt.loaded.ext.module.maasng:1008][WARNING ][5767] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-03-23 05:31:16,715 [salt.loaded.ext.module.maasng:1011][WARNING ][5767] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-03-23 05:31:16,781 [salt.state       :300 ][INFO    ][5767] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-03-23 05:31:16,781 [salt.state       :1951][INFO    ][5767] Completed state [pxe_admin] at time 05:31:16.781752 duration_in_ms=165.666
2019-03-23 05:31:16,782 [salt.state       :1780][INFO    ][5767] Running state [vlan 0] at time 05:31:16.782179
2019-03-23 05:31:16,782 [salt.state       :1813][INFO    ][5767] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-23 05:31:16,828 [salt.loaded.ext.module.maasng:945 ][INFO    ][5767] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': u'qsd8ah', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-03-23 05:31:16,930 [salt.loaded.ext.module.maasng:945 ][INFO    ][5767] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-23 05:31:17,163 [salt.loaded.ext.module.maasng:945 ][INFO    ][5767] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-03-23 05:31:17,224 [salt.state       :300 ][INFO    ][5767] {'new': 'Vlan untagged was updated'}
2019-03-23 05:31:17,225 [salt.state       :1951][INFO    ][5767] Completed state [vlan 0] at time 05:31:17.224992 duration_in_ms=442.813
2019-03-23 05:31:17,226 [salt.state       :1780][INFO    ][5767] Running state [192.168.11.0/24] at time 05:31:17.226530
2019-03-23 05:31:17,227 [salt.state       :1813][INFO    ][5767] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-03-23 05:31:17,393 [salt.loaded.ext.module.maasng:945 ][INFO    ][5767] [{u'id': 0, 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, 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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, 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'qsd8ah', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-23 05:31:17,394 [salt.loaded.ext.module.maasng:1235][WARNING ][5767] Ignoring parameter vlan:0
2019-03-23 05:31:17,453 [salt.state       :300 ][INFO    ][5767] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-03-23 05:31:17,453 [salt.state       :1951][INFO    ][5767] Completed state [192.168.11.0/24] at time 05:31:17.453345 duration_in_ms=226.814
2019-03-23 05:31:17,454 [salt.state       :1780][INFO    ][5767] Running state [maas_create_iprange_1] at time 05:31:17.454628
2019-03-23 05:31:17,455 [salt.state       :1813][INFO    ][5767] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-03-23 05:31:17,500 [salt.state       :300 ][INFO    ][5767] Iprange maas_create_iprange_1 already exist.
2019-03-23 05:31:17,500 [salt.state       :1951][INFO    ][5767] Completed state [maas_create_iprange_1] at time 05:31:17.500837 duration_in_ms=46.21
2019-03-23 05:31:17,501 [salt.state       :1780][INFO    ][5767] Running state [vlan 0] at time 05:31:17.501179
2019-03-23 05:31:17,501 [salt.state       :1813][INFO    ][5767] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-03-23 05:31:17,536 [salt.loaded.ext.module.maasng:945 ][INFO    ][5767] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-23 05:31:17,602 [salt.loaded.ext.module.maasng:945 ][INFO    ][5767] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': u'qsd8ah', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-03-23 05:31:17,818 [salt.loaded.ext.module.maasng:945 ][INFO    ][5767] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-03-23 05:31:17,884 [salt.state       :300 ][INFO    ][5767] {'new': 'Vlan untagged was updated'}
2019-03-23 05:31:17,884 [salt.state       :1951][INFO    ][5767] Completed state [vlan 0] at time 05:31:17.884462 duration_in_ms=383.284
2019-03-23 05:31:17,885 [salt.state       :1780][INFO    ][5767] Running state [opnfv] at time 05:31:17.884977
2019-03-23 05:31:17,885 [salt.state       :1813][INFO    ][5767] Executing state maasng.sshkey_present for [opnfv]
2019-03-23 05:31:17,939 [salt.loaded.ext.module.maasng:1903][INFO    ][5767] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-03-23 05:31:17,939 [salt.state       :300 ][INFO    ][5767] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-03-23 05:31:17,940 [salt.state       :1951][INFO    ][5767] Completed state [opnfv] at time 05:31:17.940108 duration_in_ms=55.13
2019-03-23 05:31:17,941 [salt.state       :1780][INFO    ][5767] Running state [maas.process_tags] at time 05:31:17.941185
2019-03-23 05:31:17,941 [salt.state       :1813][INFO    ][5767] Executing state module.run for [maas.process_tags]
2019-03-23 05:31:17,942 [salt.utils.decorators:613 ][WARNING ][5767] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:31:17,991 [salt.loaded.ext.module.maas:92  ][INFO    ][5767] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-03-23 05:31:18,034 [salt.state       :300 ][INFO    ][5767] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-03-23 05:31:18,034 [salt.state       :1951][INFO    ][5767] Completed state [maas.process_tags] at time 05:31:18.034378 duration_in_ms=93.193
2019-03-23 05:31:18,037 [salt.minion      :1711][INFO    ][5767] Returning information for job: 20190323053031441362
2019-03-23 05:31:18,585 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command state.apply with jid 20190323053118574052
2019-03-23 05:31:18,603 [salt.minion      :1432][INFO    ][6161] Starting a new job with PID 6161
2019-03-23 05:31:22,297 [salt.state       :915 ][INFO    ][6161] Loading fresh modules for state activity
2019-03-23 05:31:22,331 [salt.fileclient  :1219][INFO    ][6161] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-03-23 05:31:22,359 [salt.state       :1780][INFO    ][6161] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:22.359174
2019-03-23 05:31:22,359 [salt.state       :1813][INFO    ][6161] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-23 05:31:22,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6161] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-23 05:31:23,801 [salt.state       :300 ][INFO    ][6161] {'pid': 6185, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-23 05:31:23,802 [salt.state       :1951][INFO    ][6161] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:23.802483 duration_in_ms=1443.307
2019-03-23 05:31:23,805 [salt.state       :1780][INFO    ][6161] Running state [maas.process_machines] at time 05:31:23.805142
2019-03-23 05:31:23,805 [salt.state       :1813][INFO    ][6161] Executing state module.run for [maas.process_machines]
2019-03-23 05:31:23,806 [salt.utils.decorators:613 ][WARNING ][6161] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:31:23,879 [salt.loaded.ext.module.maas:412 ][WARNING ][6161] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-23 05:31:23,880 [salt.loaded.ext.module.maas:92  ][INFO    ][6161] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-03-23 05:31:25,444 [salt.loaded.ext.module.maas:412 ][WARNING ][6161] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-23 05:31:25,445 [salt.loaded.ext.module.maas:92  ][INFO    ][6161] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-03-23 05:31:26,874 [salt.loaded.ext.module.maas:412 ][WARNING ][6161] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-23 05:31:26,875 [salt.loaded.ext.module.maas:92  ][INFO    ][6161] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-03-23 05:31:28,269 [salt.loaded.ext.module.maas:412 ][WARNING ][6161] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-23 05:31:28,270 [salt.loaded.ext.module.maas:92  ][INFO    ][6161] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-03-23 05:31:29,718 [salt.loaded.ext.module.maas:412 ][WARNING ][6161] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-03-23 05:31:29,719 [salt.loaded.ext.module.maas:92  ][INFO    ][6161] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-03-23 05:31:31,126 [salt.state       :300 ][INFO    ][6161] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-23 05:31:31,126 [salt.state       :1951][INFO    ][6161] Completed state [maas.process_machines] at time 05:31:31.126716 duration_in_ms=7321.572
2019-03-23 05:31:31,143 [salt.minion      :1711][INFO    ][6161] Returning information for job: 20190323053118574052
2019-03-23 05:32:02,228 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command state.apply with jid 20190323053202213287
2019-03-23 05:32:02,248 [salt.minion      :1432][INFO    ][6500] Starting a new job with PID 6500
2019-03-23 05:32:05,707 [salt.state       :915 ][INFO    ][6500] Loading fresh modules for state activity
2019-03-23 05:32:05,760 [salt.fileclient  :1219][INFO    ][6500] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-03-23 05:32:05,800 [salt.state       :1780][INFO    ][6500] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:05.800754
2019-03-23 05:32:05,801 [salt.state       :1813][INFO    ][6500] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-23 05:32:05,803 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6500] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-23 05:32:07,234 [salt.state       :300 ][INFO    ][6500] {'pid': 6507, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-23 05:32:07,235 [salt.state       :1951][INFO    ][6500] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:07.235188 duration_in_ms=1434.435
2019-03-23 05:32:07,236 [salt.state       :1780][INFO    ][6500] Running state [maas.wait_for_machine_status] at time 05:32:07.236364
2019-03-23 05:32:07,236 [salt.state       :1813][INFO    ][6500] Executing state module.run for [maas.wait_for_machine_status]
2019-03-23 05:32:07,236 [salt.utils.decorators:613 ][WARNING ][6500] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:32:08,252 [salt.loaded.ext.module.maas:1023][INFO    ][6500] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.9888308s left)
2019-03-23 05:32:17,326 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053217313887
2019-03-23 05:32:17,349 [salt.minion      :1432][INFO    ][6543] Starting a new job with PID 6543
2019-03-23 05:32:17,371 [salt.minion      :1711][INFO    ][6543] Returning information for job: 20190323053217313887
2019-03-23 05:32:39,265 [salt.loaded.ext.module.maas:1023][INFO    ][6500] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.976403s left)
2019-03-23 05:32:47,367 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053247354025
2019-03-23 05:32:47,388 [salt.minion      :1432][INFO    ][6581] Starting a new job with PID 6581
2019-03-23 05:32:47,411 [salt.minion      :1711][INFO    ][6581] Returning information for job: 20190323053247354025
2019-03-23 05:33:10,236 [salt.loaded.ext.module.maas:1023][INFO    ][6500] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.00528479s left)
2019-03-23 05:33:17,427 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053317421500
2019-03-23 05:33:17,445 [salt.minion      :1432][INFO    ][6734] Starting a new job with PID 6734
2019-03-23 05:33:17,467 [salt.minion      :1711][INFO    ][6734] Returning information for job: 20190323053317421500
2019-03-23 05:33:41,576 [salt.loaded.ext.module.maas:1023][INFO    ][6500] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.66501498s left)
2019-03-23 05:33:47,474 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053347462228
2019-03-23 05:33:47,497 [salt.minion      :1432][INFO    ][6947] Starting a new job with PID 6947
2019-03-23 05:33:47,521 [salt.minion      :1711][INFO    ][6947] Returning information for job: 20190323053347462228
2019-03-23 05:34:13,294 [salt.loaded.ext.module.maas:1023][INFO    ][6500] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.94756484s left)
2019-03-23 05:34:17,535 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053417529206
2019-03-23 05:34:17,547 [salt.minion      :1432][INFO    ][7334] Starting a new job with PID 7334
2019-03-23 05:34:17,560 [salt.minion      :1711][INFO    ][7334] Returning information for job: 20190323053417529206
2019-03-23 05:34:44,901 [salt.loaded.ext.module.maas:1023][INFO    ][6500] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.34068394s left)
2019-03-23 05:34:47,560 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053447547549
2019-03-23 05:34:47,583 [salt.minion      :1432][INFO    ][7647] Starting a new job with PID 7647
2019-03-23 05:34:47,606 [salt.minion      :1711][INFO    ][7647] Returning information for job: 20190323053447547549
2019-03-23 05:35:17,628 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053517612371
2019-03-23 05:35:17,650 [salt.minion      :1432][INFO    ][7912] Starting a new job with PID 7912
2019-03-23 05:35:17,672 [salt.minion      :1711][INFO    ][7912] Returning information for job: 20190323053517612371
2019-03-23 05:35:18,078 [salt.state       :300 ][INFO    ][6500] {'ret': True}
2019-03-23 05:35:18,078 [salt.state       :1951][INFO    ][6500] Completed state [maas.wait_for_machine_status] at time 05:35:18.078710 duration_in_ms=190842.344
2019-03-23 05:35:18,082 [salt.minion      :1711][INFO    ][6500] Returning information for job: 20190323053202213287
2019-03-23 05:35:18,709 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command state.apply with jid 20190323053518696462
2019-03-23 05:35:18,731 [salt.minion      :1432][INFO    ][7919] Starting a new job with PID 7919
2019-03-23 05:35:22,265 [salt.state       :915 ][INFO    ][7919] Loading fresh modules for state activity
2019-03-23 05:35:22,316 [salt.fileclient  :1219][INFO    ][7919] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-03-23 05:35:22,399 [salt.state       :1780][INFO    ][7919] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:22.399108
2019-03-23 05:35:22,399 [salt.state       :1813][INFO    ][7919] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-23 05:35:22,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7919] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-23 05:35:23,939 [salt.state       :300 ][INFO    ][7919] {'pid': 7936, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-23 05:35:23,940 [salt.state       :1951][INFO    ][7919] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:23.940447 duration_in_ms=1541.337
2019-03-23 05:35:23,943 [salt.state       :1780][INFO    ][7919] Running state [maas_machines_storage_cmp002_lvm] at time 05:35:23.943747
2019-03-23 05:35:23,944 [salt.state       :1813][INFO    ][7919] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-03-23 05:35:25,431 [salt.loaded.ext.module.maasng:610 ][INFO    ][7919] agwewc
2019-03-23 05:35:25,431 [salt.loaded.ext.module.maasng:626 ][INFO    ][7919] sda
2019-03-23 05:35:26,036 [salt.loaded.ext.module.maasng:361 ][INFO    ][7919] agwewc
2019-03-23 05:35:26,156 [salt.loaded.ext.module.maasng:367 ][INFO    ][7919] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'7478e319-fdb5-4768-ab19-004a2557b9e1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'agwewc', u'device_id': 4, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'0d524672-6f39-4e07-8511-021056049e5c', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/blockdevices/4/partition/4', u'type': u'partition', u'id': 4, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'agwewc', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/blockdevices/4/', u'type': u'physical', u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'uuid': u'7aa08bb0-daa0-4db1-a28d-3085016cbcee', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'agwewc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'28069674-29ab-4e22-b7cc-3b2a7ac2ffe7', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/blockdevices/9/', u'type': u'virtual', u'id': 9, u'serial': None, u'size': 2397988454400}]
2019-03-23 05:35:26,157 [salt.loaded.ext.module.maasng:632 ][INFO    ][7919] vgroot
2019-03-23 05:35:26,158 [salt.loaded.ext.module.maasng:635 ][INFO    ][7919] lvroot
2019-03-23 05:35:26,158 [salt.loaded.ext.module.maasng:639 ][INFO    ][7919] 107374182400
2019-03-23 05:35:26,648 [salt.loaded.ext.module.maasng:645 ][INFO    ][7919] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'modules-final', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'24ab0b78-a973-44f6-af0f-b91fdff3b5cc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'agwewc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'72f42610-1ec5-4d93-bf2e-0da197f653b3', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/blockdevices/4/partition/6'}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'agwewc', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'name': u'sda'}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'agwewc', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"'cloudinit' running modules for final", u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'536ba26e-e815-4b55-bbef-cbbaff590358', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'agwewc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'788dcc38-39c6-491c-97c2-679d6b4bc4ab', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'24ab0b78-a973-44f6-af0f-b91fdff3b5cc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'agwewc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'72f42610-1ec5-4d93-bf2e-0da197f653b3', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/blockdevices/4/partition/6'}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'agwewc', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'name': u'sda'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'536ba26e-e815-4b55-bbef-cbbaff590358', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'agwewc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'788dcc38-39c6-491c-97c2-679d6b4bc4ab', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'name': u'vgroot-lvroot'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'on', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'24ab0b78-a973-44f6-af0f-b91fdff3b5cc', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'agwewc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'72f42610-1ec5-4d93-bf2e-0da197f653b3', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/blockdevices/4/partition/6'}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'agwewc', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'agwewc', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 31}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'agwewc', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/interfaces/4/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 31}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'agwewc', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/interfaces/4/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 32}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'agwewc', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/interfaces/18/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 34}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'agwewc', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/interfaces/19/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 35}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'agwewc', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/agwewc/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/agwewc/'}
2019-03-23 05:35:26,651 [salt.state       :300 ][INFO    ][7919] {'new': {'storage_layout': 'lvm'}}
2019-03-23 05:35:26,651 [salt.state       :1951][INFO    ][7919] Completed state [maas_machines_storage_cmp002_lvm] at time 05:35:26.651792 duration_in_ms=2708.044
2019-03-23 05:35:26,652 [salt.state       :1780][INFO    ][7919] Running state [maas_machines_storage_cmp001_lvm] at time 05:35:26.652352
2019-03-23 05:35:26,652 [salt.state       :1813][INFO    ][7919] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-03-23 05:35:28,156 [salt.loaded.ext.module.maasng:610 ][INFO    ][7919] mpetgm
2019-03-23 05:35:28,157 [salt.loaded.ext.module.maasng:626 ][INFO    ][7919] sda
2019-03-23 05:35:28,952 [salt.loaded.ext.module.maasng:361 ][INFO    ][7919] mpetgm
2019-03-23 05:35:29,066 [salt.loaded.ext.module.maasng:367 ][INFO    ][7919] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'8e693602-deb5-451c-bbaf-5be35e9ecbb1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mpetgm', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8d9388f0-e41b-4e0f-be2f-e96c1a87f2c8', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/blockdevices/1/partition/1'}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'mpetgm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/blockdevices/1/', u'used_size': 2397998940160, u'id': 1, u'name': u'sda'}, {u'size': 2397988454400, u'model': None, u'block_size': 4096, u'uuid': u'9f117472-559c-408c-ac6e-105606755492', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'mpetgm', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1acf0cab-0862-41a2-93e7-ae75967d5471', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/blockdevices/6/', u'used_size': 2397988454400, u'id': 6, u'name': u'vgroot-lvroot'}]
2019-03-23 05:35:29,067 [salt.loaded.ext.module.maasng:632 ][INFO    ][7919] vgroot
2019-03-23 05:35:29,067 [salt.loaded.ext.module.maasng:635 ][INFO    ][7919] lvroot
2019-03-23 05:35:29,067 [salt.loaded.ext.module.maasng:639 ][INFO    ][7919] 107374182400
2019-03-23 05:35:29,444 [salt.loaded.ext.module.maasng:645 ][INFO    ][7919] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, 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'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/mpetgm/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'owner_data': {}, u'system_id': u'mpetgm', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'78b0ffb4-c6e1-4421-b110-2661cc4050ac', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mpetgm', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'390e6928-549f-4a31-8f78-378181ab6300', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'81aa4ba6-1ee2-48b9-a9df-f79bc549116a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mpetgm', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'2e95a904-042f-4b67-aca3-6e6c043a5951', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'mpetgm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'78b0ffb4-c6e1-4421-b110-2661cc4050ac', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mpetgm', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'390e6928-549f-4a31-8f78-378181ab6300', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'81aa4ba6-1ee2-48b9-a9df-f79bc549116a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mpetgm', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'2e95a904-042f-4b67-aca3-6e6c043a5951', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'mpetgm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mpetgm', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'81aa4ba6-1ee2-48b9-a9df-f79bc549116a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mpetgm', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'2e95a904-042f-4b67-aca3-6e6c043a5951', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'mpetgm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 15}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'system_id': u'mpetgm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/interfaces/5/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 16}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 9, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'system_id': u'mpetgm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/interfaces/9/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 10, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'system_id': u'mpetgm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/interfaces/10/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'system_id': u'mpetgm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/interfaces/11/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'ip_addresses': [u'192.168.11.38'], u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 15}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qsd8ah', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'system_id': u'mpetgm', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mpetgm/interfaces/5/'}, u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-03-23 05:35:29,446 [salt.state       :300 ][INFO    ][7919] {'new': {'storage_layout': 'lvm'}}
2019-03-23 05:35:29,447 [salt.state       :1951][INFO    ][7919] Completed state [maas_machines_storage_cmp001_lvm] at time 05:35:29.447141 duration_in_ms=2794.789
2019-03-23 05:35:29,450 [salt.minion      :1711][INFO    ][7919] Returning information for job: 20190323053518696462
2019-03-23 05:35:30,092 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command state.apply with jid 20190323053530076250
2019-03-23 05:35:30,114 [salt.minion      :1432][INFO    ][7988] Starting a new job with PID 7988
2019-03-23 05:35:30,792 [salt.state       :915 ][INFO    ][7988] Loading fresh modules for state activity
2019-03-23 05:35:30,844 [salt.fileclient  :1219][INFO    ][7988] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-03-23 05:35:30,890 [salt.state       :1780][INFO    ][7988] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:30.890224
2019-03-23 05:35:30,890 [salt.state       :1813][INFO    ][7988] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-23 05:35:30,893 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7988] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-23 05:35:32,325 [salt.state       :300 ][INFO    ][7988] {'pid': 7995, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-23 05:35:32,325 [salt.state       :1951][INFO    ][7988] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:32.325826 duration_in_ms=1435.604
2019-03-23 05:35:32,327 [salt.state       :1780][INFO    ][7988] Running state [maas.deploy_machines] at time 05:35:32.327283
2019-03-23 05:35:32,327 [salt.state       :1813][INFO    ][7988] Executing state module.run for [maas.deploy_machines]
2019-03-23 05:35:32,327 [salt.utils.decorators:613 ][WARNING ][7988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:35:32,870 [salt.loaded.ext.module.maas:684 ][INFO    ][7988] deploymachines hwe_kernel=hwe-16.04 system_id=agwewc distro_series=xenial
2019-03-23 05:35:34,911 [salt.loaded.ext.module.maas:684 ][INFO    ][7988] deploymachines hwe_kernel=hwe-16.04 system_id=mpetgm distro_series=xenial
2019-03-23 05:35:37,402 [salt.loaded.ext.module.maas:684 ][INFO    ][7988] deploymachines hwe_kernel=hwe-16.04 system_id=fq67fc distro_series=xenial
2019-03-23 05:35:39,744 [salt.loaded.ext.module.maas:684 ][INFO    ][7988] deploymachines hwe_kernel=hwe-16.04 system_id=xt7cey distro_series=xenial
2019-03-23 05:35:42,301 [salt.loaded.ext.module.maas:684 ][INFO    ][7988] deploymachines hwe_kernel=hwe-16.04 system_id=amx3a3 distro_series=xenial
2019-03-23 05:35:44,399 [salt.state       :300 ][INFO    ][7988] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-03-23 05:35:44,399 [salt.state       :1951][INFO    ][7988] Completed state [maas.deploy_machines] at time 05:35:44.399597 duration_in_ms=12072.312
2019-03-23 05:35:44,403 [salt.minion      :1711][INFO    ][7988] Returning information for job: 20190323053530076250
2019-03-23 05:35:45,010 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command state.apply with jid 20190323053544994264
2019-03-23 05:35:45,033 [salt.minion      :1432][INFO    ][8281] Starting a new job with PID 8281
2019-03-23 05:35:48,552 [salt.state       :915 ][INFO    ][8281] Loading fresh modules for state activity
2019-03-23 05:35:48,604 [salt.fileclient  :1219][INFO    ][8281] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-03-23 05:35:48,645 [salt.state       :1780][INFO    ][8281] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:48.644958
2019-03-23 05:35:48,645 [salt.state       :1813][INFO    ][8281] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-03-23 05:35:48,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8281] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-03-23 05:35:50,052 [salt.state       :300 ][INFO    ][8281] {'pid': 8295, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-03-23 05:35:50,053 [salt.state       :1951][INFO    ][8281] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:50.053289 duration_in_ms=1408.332
2019-03-23 05:35:50,054 [salt.state       :1780][INFO    ][8281] Running state [maas.wait_for_machine_status] at time 05:35:50.054443
2019-03-23 05:35:50,054 [salt.state       :1813][INFO    ][8281] Executing state module.run for [maas.wait_for_machine_status]
2019-03-23 05:35:50,054 [salt.utils.decorators:613 ][WARNING ][8281] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-03-23 05:35:53,217 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.84256792s left)
2019-03-23 05:36:00,061 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053600048109
2019-03-23 05:36:00,084 [salt.minion      :1432][INFO    ][8310] Starting a new job with PID 8310
2019-03-23 05:36:00,109 [salt.minion      :1711][INFO    ][8310] Returning information for job: 20190323053600048109
2019-03-23 05:36:26,665 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.39444995s left)
2019-03-23 05:36:30,109 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053630095983
2019-03-23 05:36:30,131 [salt.minion      :1432][INFO    ][8349] Starting a new job with PID 8349
2019-03-23 05:36:30,154 [salt.minion      :1711][INFO    ][8349] Returning information for job: 20190323053630095983
2019-03-23 05:37:00,151 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.90829086s left)
2019-03-23 05:37:00,182 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053700165646
2019-03-23 05:37:00,205 [salt.minion      :1432][INFO    ][8383] Starting a new job with PID 8383
2019-03-23 05:37:00,228 [salt.minion      :1711][INFO    ][8383] Returning information for job: 20190323053700165646
2019-03-23 05:37:30,229 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053730218120
2019-03-23 05:37:30,246 [salt.minion      :1432][INFO    ][8510] Starting a new job with PID 8510
2019-03-23 05:37:30,269 [salt.minion      :1711][INFO    ][8510] Returning information for job: 20190323053730218120
2019-03-23 05:37:32,902 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.15743184s left)
2019-03-23 05:38:00,282 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053800269823
2019-03-23 05:38:00,305 [salt.minion      :1432][INFO    ][8712] Starting a new job with PID 8712
2019-03-23 05:38:00,330 [salt.minion      :1711][INFO    ][8712] Returning information for job: 20190323053800269823
2019-03-23 05:38:06,616 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.44302297s left)
2019-03-23 05:38:30,342 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053830329153
2019-03-23 05:38:30,364 [salt.minion      :1432][INFO    ][9251] Starting a new job with PID 9251
2019-03-23 05:38:30,387 [salt.minion      :1711][INFO    ][9251] Returning information for job: 20190323053830329153
2019-03-23 05:38:39,692 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.36710191s left)
2019-03-23 05:39:00,391 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053900378801
2019-03-23 05:39:00,414 [salt.minion      :1432][INFO    ][9458] Starting a new job with PID 9458
2019-03-23 05:39:00,437 [salt.minion      :1711][INFO    ][9458] Returning information for job: 20190323053900378801
2019-03-23 05:39:12,997 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.06263995s left)
2019-03-23 05:39:30,450 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323053930437238
2019-03-23 05:39:30,473 [salt.minion      :1432][INFO    ][9626] Starting a new job with PID 9626
2019-03-23 05:39:30,496 [salt.minion      :1711][INFO    ][9626] Returning information for job: 20190323053930437238
2019-03-23 05:39:46,229 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.83080196s left)
2019-03-23 05:40:00,512 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323054000499788
2019-03-23 05:40:00,535 [salt.minion      :1432][INFO    ][9704] Starting a new job with PID 9704
2019-03-23 05:40:00,558 [salt.minion      :1711][INFO    ][9704] Returning information for job: 20190323054000499788
2019-03-23 05:40:19,016 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.04336596s left)
2019-03-23 05:40:30,579 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323054030566252
2019-03-23 05:40:30,602 [salt.minion      :1432][INFO    ][9910] Starting a new job with PID 9910
2019-03-23 05:40:30,626 [salt.minion      :1711][INFO    ][9910] Returning information for job: 20190323054030566252
2019-03-23 05:40:52,550 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.50907588s left)
2019-03-23 05:41:00,656 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323054100644080
2019-03-23 05:41:00,679 [salt.minion      :1432][INFO    ][10084] Starting a new job with PID 10084
2019-03-23 05:41:00,702 [salt.minion      :1711][INFO    ][10084] Returning information for job: 20190323054100644080
2019-03-23 05:41:26,141 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.9186058s left)
2019-03-23 05:41:30,733 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323054130720618
2019-03-23 05:41:30,756 [salt.minion      :1432][INFO    ][10356] Starting a new job with PID 10356
2019-03-23 05:41:30,780 [salt.minion      :1711][INFO    ][10356] Returning information for job: 20190323054130720618
2019-03-23 05:41:59,514 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.54488683s left)
2019-03-23 05:42:00,821 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323054200808938
2019-03-23 05:42:00,843 [salt.minion      :1432][INFO    ][10394] Starting a new job with PID 10394
2019-03-23 05:42:00,867 [salt.minion      :1711][INFO    ][10394] Returning information for job: 20190323054200808938
2019-03-23 05:42:30,917 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323054230905225
2019-03-23 05:42:30,939 [salt.minion      :1432][INFO    ][10495] Starting a new job with PID 10495
2019-03-23 05:42:30,961 [salt.minion      :1711][INFO    ][10495] Returning information for job: 20190323054230905225
2019-03-23 05:42:32,933 [salt.loaded.ext.module.maas:1023][INFO    ][8281] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.12630296s left)
2019-03-23 05:43:01,056 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command saltutil.find_job with jid 20190323054301045305
2019-03-23 05:43:01,076 [salt.minion      :1432][INFO    ][10719] Starting a new job with PID 10719
2019-03-23 05:43:01,100 [salt.minion      :1711][INFO    ][10719] Returning information for job: 20190323054301045305
2019-03-23 05:43:06,152 [salt.state       :300 ][INFO    ][8281] {'ret': True}
2019-03-23 05:43:06,153 [salt.state       :1951][INFO    ][8281] Completed state [maas.wait_for_machine_status] at time 05:43:06.152952 duration_in_ms=436098.507
2019-03-23 05:43:06,156 [salt.minion      :1711][INFO    ][8281] Returning information for job: 20190323053544994264
2019-03-23 06:14:12,786 [salt.utils.schedule:1377][INFO    ][3350] Running scheduled job: __mine_interval
2019-03-23 07:14:12,785 [salt.utils.schedule:1377][INFO    ][3350] Running scheduled job: __mine_interval
2019-03-23 07:41:58,906 [salt.minion      :1308][INFO    ][3350] User sudo_ubuntu Executing command cp.push_dir with jid 20190323074158893597
2019-03-23 07:41:58,924 [salt.minion      :1432][INFO    ][19169] Starting a new job with PID 19169
