2019-06-02 03:39:44,965 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-02 03:40:35,010 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-02 03:41:25,055 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-02 03:42:15,102 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-02 03:43:05,153 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-02 03:45:12,484 [salt.utils.decorators:613 ][WARNING ][2705] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 03:45:12,903 [salt.utils.decorators:613 ][WARNING ][2705] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 03:45:14,952 [salt.loaded.int.states.file:2298][WARNING ][2841] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-02 03:45:20,270 [salt.state       :2022][WARNING ][2954] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-02 03:45:22,849 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 04:00:27,400 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 04:28:30,220 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 04:43:32,144 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 04:43:36,538 [salt.loaded.ext.module.maasng:1008][WARNING ][2954] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-02 04:43:36,539 [salt.loaded.ext.module.maasng:1011][WARNING ][2954] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-02 04:43:37,135 [salt.loaded.ext.module.maasng:1235][WARNING ][2954] Ignoring parameter vlan:0
2019-06-02 04:43:43,888 [salt.utils.decorators:613 ][WARNING ][11074] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 04:43:43,948 [salt.loaded.ext.module.maas:412 ][WARNING ][11074] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:43:48,045 [salt.loaded.ext.module.maas:412 ][WARNING ][11074] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:43:51,686 [salt.loaded.ext.module.maas:412 ][WARNING ][11074] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:43:55,563 [salt.loaded.ext.module.maas:412 ][WARNING ][11074] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:43:58,581 [salt.loaded.ext.module.maas:412 ][WARNING ][11074] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:44:04,577 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11982] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-02 04:44:04,609 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11982] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-02 04:44:04,632 [salt.utils.parsers:1051][WARNING ][359] Minion received a SIGTERM. Exiting.
2019-06-02 04:44:05,692 [salt.cli.daemons :293 ][INFO    ][12032] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-06-02 04:44:05,790 [salt.cli.daemons :82  ][INFO    ][12032] Starting up the Salt Minion
2019-06-02 04:44:05,791 [salt.utils.event :1017][INFO    ][12032] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-06-02 04:44:06,682 [salt.minion      :976 ][INFO    ][12032] Creating minion process manager
2019-06-02 04:44:08,141 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12032] Executing command ['date', '+%z'] in directory '/root'
2019-06-02 04:44:08,161 [salt.utils.schedule:568 ][INFO    ][12032] Updating job settings for scheduled job: __mine_interval
2019-06-02 04:44:08,165 [salt.minion      :1108][INFO    ][12032] Added mine.update to scheduler
2019-06-02 04:44:08,169 [salt.minion      :1975][INFO    ][12032] Minion is starting as user 'root'
2019-06-02 04:44:08,184 [salt.minion      :2336][INFO    ][12032] Minion is ready to receive requests!
2019-06-02 04:44:32,686 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command state.apply with jid 20190602044432672212
2019-06-02 04:44:32,708 [salt.minion      :1432][INFO    ][12168] Starting a new job with PID 12168
2019-06-02 04:44:36,452 [salt.state       :915 ][INFO    ][12168] Loading fresh modules for state activity
2019-06-02 04:44:36,504 [salt.fileclient  :1219][INFO    ][12168] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-02 04:44:36,548 [salt.state       :1780][INFO    ][12168] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:44:36.548386
2019-06-02 04:44:36,548 [salt.state       :1813][INFO    ][12168] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 04:44:36,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12168] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 04:44:38,006 [salt.state       :300 ][INFO    ][12168] {'pid': 12175, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 04:44:38,007 [salt.state       :1951][INFO    ][12168] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:44:38.007370 duration_in_ms=1458.984
2019-06-02 04:44:38,009 [salt.state       :1780][INFO    ][12168] Running state [maas.wait_for_machine_status] at time 04:44:38.009888
2019-06-02 04:44:38,010 [salt.state       :1813][INFO    ][12168] Executing state module.run for [maas.wait_for_machine_status]
2019-06-02 04:44:38,011 [salt.utils.decorators:613 ][WARNING ][12168] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 04:44:38,911 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.11063313s left)
2019-06-02 04:44:47,734 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602044447720768
2019-06-02 04:44:47,756 [salt.minion      :1432][INFO    ][12192] Starting a new job with PID 12192
2019-06-02 04:44:47,780 [salt.minion      :1711][INFO    ][12192] Returning information for job: 20190602044447720768
2019-06-02 04:45:09,904 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.11711311s left)
2019-06-02 04:45:17,788 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602044517775692
2019-06-02 04:45:17,808 [salt.minion      :1432][INFO    ][12231] Starting a new job with PID 12231
2019-06-02 04:45:17,829 [salt.minion      :1711][INFO    ][12231] Returning information for job: 20190602044517775692
2019-06-02 04:45:42,505 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1435.51599002s left)
2019-06-02 04:45:47,859 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602044547847569
2019-06-02 04:45:47,876 [salt.minion      :1432][INFO    ][12459] Starting a new job with PID 12459
2019-06-02 04:45:47,894 [salt.minion      :1711][INFO    ][12459] Returning information for job: 20190602044547847569
2019-06-02 04:46:15,589 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1402.43222404s left)
2019-06-02 04:46:17,915 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602044617899829
2019-06-02 04:46:17,936 [salt.minion      :1432][INFO    ][12569] Starting a new job with PID 12569
2019-06-02 04:46:17,958 [salt.minion      :1711][INFO    ][12569] Returning information for job: 20190602044617899829
2019-06-02 04:46:47,989 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602044647980672
2019-06-02 04:46:48,010 [salt.minion      :1432][INFO    ][12661] Starting a new job with PID 12661
2019-06-02 04:46:48,024 [salt.minion      :1711][INFO    ][12661] Returning information for job: 20190602044647980672
2019-06-02 04:46:48,383 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1369.63786411s left)
2019-06-02 04:47:18,055 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602044718045243
2019-06-02 04:47:18,075 [salt.minion      :1432][INFO    ][12698] Starting a new job with PID 12698
2019-06-02 04:47:18,096 [salt.minion      :1711][INFO    ][12698] Returning information for job: 20190602044718045243
2019-06-02 04:47:22,347 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1335.67378998s left)
2019-06-02 04:47:48,132 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602044748119869
2019-06-02 04:47:48,152 [salt.minion      :1432][INFO    ][12747] Starting a new job with PID 12747
2019-06-02 04:47:48,175 [salt.minion      :1711][INFO    ][12747] Returning information for job: 20190602044748119869
2019-06-02 04:48:18,230 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602044818216923
2019-06-02 04:48:18,249 [salt.minion      :1432][INFO    ][12819] Starting a new job with PID 12819
2019-06-02 04:48:18,271 [salt.minion      :1711][INFO    ][12819] Returning information for job: 20190602044818216923
2019-06-02 04:48:48,316 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602044848303083
2019-06-02 04:48:48,334 [salt.minion      :1432][INFO    ][12864] Starting a new job with PID 12864
2019-06-02 04:48:48,355 [salt.minion      :1711][INFO    ][12864] Returning information for job: 20190602044848303083
2019-06-02 04:49:18,439 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602044918426597
2019-06-02 04:49:18,460 [salt.minion      :1432][INFO    ][12897] Starting a new job with PID 12897
2019-06-02 04:49:18,482 [salt.minion      :1711][INFO    ][12897] Returning information for job: 20190602044918426597
2019-06-02 04:49:48,534 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602044948520560
2019-06-02 04:49:48,556 [salt.minion      :1432][INFO    ][12934] Starting a new job with PID 12934
2019-06-02 04:49:48,581 [salt.minion      :1711][INFO    ][12934] Returning information for job: 20190602044948520560
2019-06-02 04:50:18,630 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045018619811
2019-06-02 04:50:18,652 [salt.minion      :1432][INFO    ][12960] Starting a new job with PID 12960
2019-06-02 04:50:18,677 [salt.minion      :1711][INFO    ][12960] Returning information for job: 20190602045018619811
2019-06-02 04:50:48,743 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045048731340
2019-06-02 04:50:48,761 [salt.minion      :1432][INFO    ][12994] Starting a new job with PID 12994
2019-06-02 04:50:48,781 [salt.minion      :1711][INFO    ][12994] Returning information for job: 20190602045048731340
2019-06-02 04:51:18,853 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045118840541
2019-06-02 04:51:18,875 [salt.minion      :1432][INFO    ][13012] Starting a new job with PID 13012
2019-06-02 04:51:18,900 [salt.minion      :1711][INFO    ][13012] Returning information for job: 20190602045118840541
2019-06-02 04:51:40,146 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1077.875494s left)
2019-06-02 04:51:48,972 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045148962873
2019-06-02 04:51:48,994 [salt.minion      :1432][INFO    ][13335] Starting a new job with PID 13335
2019-06-02 04:51:49,012 [salt.minion      :1711][INFO    ][13335] Returning information for job: 20190602045148962873
2019-06-02 04:52:11,705 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1046.31629109s left)
2019-06-02 04:52:19,071 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045219058407
2019-06-02 04:52:19,094 [salt.minion      :1432][INFO    ][13411] Starting a new job with PID 13411
2019-06-02 04:52:19,117 [salt.minion      :1711][INFO    ][13411] Returning information for job: 20190602045219058407
2019-06-02 04:52:43,360 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1014.66091394s left)
2019-06-02 04:52:49,182 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045249169602
2019-06-02 04:52:49,204 [salt.minion      :1432][INFO    ][13691] Starting a new job with PID 13691
2019-06-02 04:52:49,228 [salt.minion      :1711][INFO    ][13691] Returning information for job: 20190602045249169602
2019-06-02 04:53:15,887 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (982.134661913s left)
2019-06-02 04:53:19,297 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045319284276
2019-06-02 04:53:19,322 [salt.minion      :1432][INFO    ][13779] Starting a new job with PID 13779
2019-06-02 04:53:19,344 [salt.minion      :1711][INFO    ][13779] Returning information for job: 20190602045319284276
2019-06-02 04:53:47,980 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (950.04074192s left)
2019-06-02 04:53:49,418 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045349405227
2019-06-02 04:53:49,439 [salt.minion      :1432][INFO    ][13878] Starting a new job with PID 13878
2019-06-02 04:53:49,462 [salt.minion      :1711][INFO    ][13878] Returning information for job: 20190602045349405227
2019-06-02 04:54:19,542 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045419529601
2019-06-02 04:54:19,564 [salt.minion      :1432][INFO    ][13905] Starting a new job with PID 13905
2019-06-02 04:54:19,589 [salt.minion      :1711][INFO    ][13905] Returning information for job: 20190602045419529601
2019-06-02 04:54:20,351 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (917.670351028s left)
2019-06-02 04:54:49,679 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045449666387
2019-06-02 04:54:49,701 [salt.minion      :1432][INFO    ][13974] Starting a new job with PID 13974
2019-06-02 04:54:49,724 [salt.minion      :1711][INFO    ][13974] Returning information for job: 20190602045449666387
2019-06-02 04:54:51,288 [salt.loaded.ext.module.maas:981 ][INFO    ][12168] Machine en6ttm deleted
2019-06-02 04:54:52,004 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:54:52,005 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] 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 system_id=gm64ap architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:54:52,912 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:54:52,913 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] 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-06-02 04:54:54,275 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:54:54,276 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] 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 system_id=f6d4q3 architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:54:55,563 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:54:55,564 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] machine hostname=kvm03 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=4emph6 architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:54:56,478 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:54:56,479 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] machine hostname=kvm02 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=hcw8d6 architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:54:58,314 [salt.loaded.ext.module.maas:981 ][INFO    ][12168] Machine 4emph6 deleted
2019-06-02 04:54:58,936 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:54:58,937 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] 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 system_id=gm64ap architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:54:59,862 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:54:59,863 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=pw4bcg architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:55:00,622 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:55:00,623 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] 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 system_id=f6d4q3 architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:55:01,695 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:55:01,695 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] 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-06-02 04:55:03,138 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:55:03,139 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] machine hostname=kvm02 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=hcw8d6 architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:55:04,518 [salt.loaded.ext.module.maas:981 ][INFO    ][12168] Machine hcw8d6 deleted
2019-06-02 04:55:05,101 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:55:05,102 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] 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 system_id=gm64ap architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:55:06,118 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:55:06,119 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] machine hostname=cmp001 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:5a'] power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus system_id=pw4bcg architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:55:07,016 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:55:07,017 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] 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 system_id=f6d4q3 architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:55:08,317 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:55:08,317 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] machine hostname=kvm03 power_type=ipmi mac_addresses=[u'00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=wf6g3s architecture=amd64/generic power_parameters_power_user=admin
2019-06-02 04:55:09,249 [salt.loaded.ext.module.maas:412 ][WARNING ][12168] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-02 04:55:09,250 [salt.loaded.ext.module.maas:92  ][INFO    ][12168] 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-06-02 04:55:10,622 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (867.399482012s left)
2019-06-02 04:55:19,831 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045519817842
2019-06-02 04:55:19,852 [salt.minion      :1432][INFO    ][14733] Starting a new job with PID 14733
2019-06-02 04:55:19,876 [salt.minion      :1711][INFO    ][14733] Returning information for job: 20190602045519817842
2019-06-02 04:55:43,113 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (834.908193111s left)
2019-06-02 04:55:49,979 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045549969825
2019-06-02 04:55:50,000 [salt.minion      :1432][INFO    ][14803] Starting a new job with PID 14803
2019-06-02 04:55:50,024 [salt.minion      :1711][INFO    ][14803] Returning information for job: 20190602045549969825
2019-06-02 04:56:15,228 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (802.793416023s left)
2019-06-02 04:56:20,139 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045620126372
2019-06-02 04:56:20,162 [salt.minion      :1432][INFO    ][14844] Starting a new job with PID 14844
2019-06-02 04:56:20,185 [salt.minion      :1711][INFO    ][14844] Returning information for job: 20190602045620126372
2019-06-02 04:56:46,949 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (771.071898937s left)
2019-06-02 04:56:50,311 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045650298546
2019-06-02 04:56:50,330 [salt.minion      :1432][INFO    ][14919] Starting a new job with PID 14919
2019-06-02 04:56:50,348 [salt.minion      :1711][INFO    ][14919] Returning information for job: 20190602045650298546
2019-06-02 04:57:19,171 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (738.85051012s left)
2019-06-02 04:57:20,471 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045720459467
2019-06-02 04:57:20,494 [salt.minion      :1432][INFO    ][15047] Starting a new job with PID 15047
2019-06-02 04:57:20,519 [salt.minion      :1711][INFO    ][15047] Returning information for job: 20190602045720459467
2019-06-02 04:57:50,669 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045750660169
2019-06-02 04:57:50,690 [salt.minion      :1432][INFO    ][15422] Starting a new job with PID 15422
2019-06-02 04:57:50,711 [salt.minion      :1711][INFO    ][15422] Returning information for job: 20190602045750660169
2019-06-02 04:57:51,598 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (706.422887087s left)
2019-06-02 04:58:20,861 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045820848918
2019-06-02 04:58:20,884 [salt.minion      :1432][INFO    ][15456] Starting a new job with PID 15456
2019-06-02 04:58:20,908 [salt.minion      :1711][INFO    ][15456] Returning information for job: 20190602045820848918
2019-06-02 04:58:23,706 [salt.loaded.ext.module.maas:1023][INFO    ][12168] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (674.315331936s left)
2019-06-02 04:58:51,079 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045851066135
2019-06-02 04:58:51,099 [salt.minion      :1432][INFO    ][15707] Starting a new job with PID 15707
2019-06-02 04:58:51,122 [salt.minion      :1711][INFO    ][15707] Returning information for job: 20190602045851066135
2019-06-02 04:58:57,061 [salt.state       :300 ][INFO    ][12168] {'ret': True}
2019-06-02 04:58:57,062 [salt.state       :1951][INFO    ][12168] Completed state [maas.wait_for_machine_status] at time 04:58:57.062174 duration_in_ms=859052.284
2019-06-02 04:58:57,065 [salt.minion      :1711][INFO    ][12168] Returning information for job: 20190602044432672212
2019-06-02 04:58:57,620 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command state.apply with jid 20190602045857608308
2019-06-02 04:58:57,639 [salt.minion      :1432][INFO    ][15727] Starting a new job with PID 15727
2019-06-02 04:59:01,297 [salt.state       :915 ][INFO    ][15727] Loading fresh modules for state activity
2019-06-02 04:59:01,349 [salt.fileclient  :1219][INFO    ][15727] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-02 04:59:01,437 [salt.state       :1780][INFO    ][15727] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:59:01.437803
2019-06-02 04:59:01,438 [salt.state       :1813][INFO    ][15727] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 04:59:01,439 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15727] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 04:59:02,726 [salt.state       :300 ][INFO    ][15727] {'pid': 15735, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 04:59:02,726 [salt.state       :1951][INFO    ][15727] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:59:02.726507 duration_in_ms=1288.705
2019-06-02 04:59:02,727 [salt.state       :1780][INFO    ][15727] Running state [maas_machines_storage_cmp002_lvm] at time 04:59:02.727923
2019-06-02 04:59:02,728 [salt.state       :1813][INFO    ][15727] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-02 04:59:04,068 [salt.loaded.ext.module.maasng:610 ][INFO    ][15727] gm64ap
2019-06-02 04:59:04,069 [salt.loaded.ext.module.maasng:626 ][INFO    ][15727] sda
2019-06-02 04:59:04,766 [salt.loaded.ext.module.maasng:361 ][INFO    ][15727] gm64ap
2019-06-02 04:59:04,897 [salt.loaded.ext.module.maasng:367 ][INFO    ][15727] [{u'size': 2397998940160, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/blockdevices/1/', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'f22a2347-f9ca-4e88-9e46-34c2a6ffecfa', u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/blockdevices/1/partition/3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gm64ap', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'241f7091-6bc9-49b0-af45-eeed594225f6', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 3, u'device_id': 1}], u'filesystem': None, u'id': 1, u'system_id': u'gm64ap', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'tags': [u'rotary'], u'name': u'sda'}, {u'size': 2397988454400, u'uuid': u'6a30496a-339d-4b26-92f4-d48aa00ed8fe', u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/blockdevices/5/', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'8d094c9a-afcc-4600-9369-14e327680463', u'fstype': u'ext4', u'label': u'root'}, u'id': 5, u'system_id': u'gm64ap', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'tags': [], u'name': u'vgroot-lvroot'}]
2019-06-02 04:59:04,898 [salt.loaded.ext.module.maasng:632 ][INFO    ][15727] vgroot
2019-06-02 04:59:04,898 [salt.loaded.ext.module.maasng:635 ][INFO    ][15727] lvroot
2019-06-02 04:59:04,899 [salt.loaded.ext.module.maasng:639 ][INFO    ][15727] 107374182400
2019-06-02 04:59:05,539 [salt.loaded.ext.module.maasng:645 ][INFO    ][15727] {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'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {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'hqyypr', 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'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'hqyypr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 24, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'gm64ap', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/interfaces/4/'}, u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'id': 1, 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'gm64ap', 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'Power state queried: off', u'ip_addresses': [], u'blockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/blockdevices/1/', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'5e6ce9a9-65aa-476e-b780-ed6756f2cc29', u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gm64ap', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'9860929c-6344-4789-acda-664a047ce348', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'filesystem': None, u'id': 1, u'system_id': u'gm64ap', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'tags': [u'rotary'], u'name': u'sda'}, {u'size': 107374182400, u'uuid': u'87fb6c03-cb83-4277-9ec4-6e074ade1c7d', u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/blockdevices/12/', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'640874cb-3310-4f92-9354-cf3a5e7c4075', u'fstype': u'ext4', u'label': u'root'}, u'id': 12, u'system_id': u'gm64ap', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'tags': [], u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'gm64ap', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'hqyypr', 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'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'hqyypr', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 24, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'gm64ap', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/interfaces/4/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'gm64ap', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/interfaces/9/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'gm64ap', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/interfaces/11/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'gm64ap', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/interfaces/13/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'5e6ce9a9-65aa-476e-b780-ed6756f2cc29', u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gm64ap', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'9860929c-6344-4789-acda-664a047ce348', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'filesystem': None, u'id': 1, u'name': u'sda', u'system_id': u'gm64ap', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/blockdevices/1/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'uuid': u'87fb6c03-cb83-4277-9ec4-6e074ade1c7d', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'640874cb-3310-4f92-9354-cf3a5e7c4075', u'fstype': u'ext4', u'label': u'root'}, u'id': 12, u'name': u'vgroot-lvroot', u'system_id': u'gm64ap', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/blockdevices/12/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'current_commissioning_result_id': 2, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/gm64ap/', u'distro_series': u'', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'5e6ce9a9-65aa-476e-b780-ed6756f2cc29', u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gm64ap', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'9860929c-6344-4789-acda-664a047ce348', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'filesystem': None, u'id': 1, u'name': u'sda', u'system_id': u'gm64ap', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/gm64ap/blockdevices/1/'}}
2019-06-02 04:59:05,541 [salt.state       :300 ][INFO    ][15727] {'new': {'storage_layout': 'lvm'}}
2019-06-02 04:59:05,541 [salt.state       :1951][INFO    ][15727] Completed state [maas_machines_storage_cmp002_lvm] at time 04:59:05.541480 duration_in_ms=2813.554
2019-06-02 04:59:05,542 [salt.state       :1780][INFO    ][15727] Running state [maas_machines_storage_cmp001_lvm] at time 04:59:05.542053
2019-06-02 04:59:05,542 [salt.state       :1813][INFO    ][15727] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-02 04:59:06,851 [salt.loaded.ext.module.maasng:610 ][INFO    ][15727] pw4bcg
2019-06-02 04:59:06,851 [salt.loaded.ext.module.maasng:626 ][INFO    ][15727] sda
2019-06-02 04:59:07,459 [salt.loaded.ext.module.maasng:361 ][INFO    ][15727] pw4bcg
2019-06-02 04:59:07,592 [salt.loaded.ext.module.maasng:367 ][INFO    ][15727] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'3fe4c8c7-8bf0-4062-aef7-7b54811d0609', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pw4bcg', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'17194753-3509-4175-b2bc-fa1145f072b6', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/blockdevices/6/partition/4'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'pw4bcg', 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'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/blockdevices/6/'}, {u'size': 2397988454400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'e430f5f1-e236-4e1f-99c5-f931bad2edee', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pw4bcg', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'2ca55209-7087-4402-a187-09b2b3f7d5e9', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/blockdevices/9/'}]
2019-06-02 04:59:07,592 [salt.loaded.ext.module.maasng:632 ][INFO    ][15727] vgroot
2019-06-02 04:59:07,593 [salt.loaded.ext.module.maasng:635 ][INFO    ][15727] lvroot
2019-06-02 04:59:07,593 [salt.loaded.ext.module.maasng:639 ][INFO    ][15727] 107374182400
2019-06-02 04:59:08,330 [salt.loaded.ext.module.maasng:645 ][INFO    ][15727] {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'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'hqyypr', 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'id': 37, u'mode': u'dhcp'}], 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'hqyypr', 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'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hqyypr', 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'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'id': 15, u'system_id': u'pw4bcg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/interfaces/15/'}, u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'owner_data': {}, u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], 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/pw4bcg/', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'pw4bcg', 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'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'92a80cd6-a39f-4d3d-a894-81f20412a6d6', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pw4bcg', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'2ddc3b26-3435-46ee-886a-625514281af2', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/blockdevices/13/'}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'3c34f84e-9df6-4a27-a68d-b8838fbb354d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pw4bcg', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6d13febe-85a6-4585-8ce0-38db3613ca49', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/blockdevices/6/partition/8'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'pw4bcg', 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'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/blockdevices/6/'}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'92a80cd6-a39f-4d3d-a894-81f20412a6d6', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pw4bcg', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'2ddc3b26-3435-46ee-886a-625514281af2', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/blockdevices/13/'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'3c34f84e-9df6-4a27-a68d-b8838fbb354d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pw4bcg', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6d13febe-85a6-4585-8ce0-38db3613ca49', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/blockdevices/6/partition/8'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'pw4bcg', 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'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/blockdevices/6/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pw4bcg', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 12, u'node_type_name': u'Machine', u'current_testing_result_id': 13, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hqyypr', 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'id': 37, u'mode': u'dhcp'}], 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'hqyypr', 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'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'hqyypr', 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'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'id': 15, u'system_id': u'pw4bcg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/interfaces/15/'}, {u'name': u'enp8s0', u'links': [{u'id': 38, u'mode': u'link_up'}], 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'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'id': 18, u'system_id': u'pw4bcg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/interfaces/18/'}, {u'name': u'enp9s0', u'links': [{u'id': 39, u'mode': u'link_up'}], 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'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'id': 19, u'system_id': u'pw4bcg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/interfaces/19/'}, {u'name': u'enp7s0', u'links': [{u'id': 40, u'mode': u'link_up'}], 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'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'id': 20, u'system_id': u'pw4bcg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/interfaces/20/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'3c34f84e-9df6-4a27-a68d-b8838fbb354d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pw4bcg', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6d13febe-85a6-4585-8ce0-38db3613ca49', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/blockdevices/6/partition/8'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'pw4bcg', 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'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/pw4bcg/blockdevices/6/'}}
2019-06-02 04:59:08,332 [salt.state       :300 ][INFO    ][15727] {'new': {'storage_layout': 'lvm'}}
2019-06-02 04:59:08,332 [salt.state       :1951][INFO    ][15727] Completed state [maas_machines_storage_cmp001_lvm] at time 04:59:08.332889 duration_in_ms=2790.835
2019-06-02 04:59:08,336 [salt.minion      :1711][INFO    ][15727] Returning information for job: 20190602045857608308
2019-06-02 04:59:08,949 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command state.apply with jid 20190602045908936890
2019-06-02 04:59:08,972 [salt.minion      :1432][INFO    ][15770] Starting a new job with PID 15770
2019-06-02 04:59:09,709 [salt.state       :915 ][INFO    ][15770] Loading fresh modules for state activity
2019-06-02 04:59:09,762 [salt.fileclient  :1219][INFO    ][15770] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-02 04:59:09,804 [salt.state       :1780][INFO    ][15770] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:59:09.804399
2019-06-02 04:59:09,804 [salt.state       :1813][INFO    ][15770] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 04:59:09,807 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15770] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 04:59:11,233 [salt.state       :300 ][INFO    ][15770] {'pid': 15778, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 04:59:11,234 [salt.state       :1951][INFO    ][15770] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:59:11.233884 duration_in_ms=1429.484
2019-06-02 04:59:11,236 [salt.state       :1780][INFO    ][15770] Running state [maas.deploy_machines] at time 04:59:11.236399
2019-06-02 04:59:11,236 [salt.state       :1813][INFO    ][15770] Executing state module.run for [maas.deploy_machines]
2019-06-02 04:59:11,238 [salt.utils.decorators:613 ][WARNING ][15770] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 04:59:11,905 [salt.loaded.ext.module.maas:684 ][INFO    ][15770] deploymachines hwe_kernel=ga-16.04 system_id=gm64ap distro_series=xenial
2019-06-02 04:59:14,602 [salt.loaded.ext.module.maas:684 ][INFO    ][15770] deploymachines hwe_kernel=ga-16.04 system_id=pw4bcg distro_series=xenial
2019-06-02 04:59:17,414 [salt.loaded.ext.module.maas:684 ][INFO    ][15770] deploymachines hwe_kernel=ga-16.04 system_id=f6d4q3 distro_series=xenial
2019-06-02 04:59:19,737 [salt.loaded.ext.module.maas:684 ][INFO    ][15770] deploymachines hwe_kernel=ga-16.04 system_id=wf6g3s distro_series=xenial
2019-06-02 04:59:22,552 [salt.loaded.ext.module.maas:684 ][INFO    ][15770] deploymachines hwe_kernel=ga-16.04 system_id=f8q66a distro_series=xenial
2019-06-02 04:59:23,986 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045923974978
2019-06-02 04:59:24,007 [salt.minion      :1432][INFO    ][16092] Starting a new job with PID 16092
2019-06-02 04:59:24,026 [salt.minion      :1711][INFO    ][16092] Returning information for job: 20190602045923974978
2019-06-02 04:59:24,706 [salt.state       :300 ][INFO    ][15770] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-02 04:59:24,706 [salt.state       :1951][INFO    ][15770] Completed state [maas.deploy_machines] at time 04:59:24.706593 duration_in_ms=13470.191
2019-06-02 04:59:24,710 [salt.minion      :1711][INFO    ][15770] Returning information for job: 20190602045908936890
2019-06-02 04:59:25,358 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command state.apply with jid 20190602045925344952
2019-06-02 04:59:25,381 [salt.minion      :1432][INFO    ][16109] Starting a new job with PID 16109
2019-06-02 04:59:29,014 [salt.state       :915 ][INFO    ][16109] Loading fresh modules for state activity
2019-06-02 04:59:29,064 [salt.fileclient  :1219][INFO    ][16109] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-02 04:59:29,104 [salt.state       :1780][INFO    ][16109] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:59:29.104122
2019-06-02 04:59:29,104 [salt.state       :1813][INFO    ][16109] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-02 04:59:29,106 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16109] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-02 04:59:30,290 [salt.state       :300 ][INFO    ][16109] {'pid': 16123, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-02 04:59:30,291 [salt.state       :1951][INFO    ][16109] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 04:59:30.291827 duration_in_ms=1187.705
2019-06-02 04:59:30,295 [salt.state       :1780][INFO    ][16109] Running state [maas.wait_for_machine_status] at time 04:59:30.295201
2019-06-02 04:59:30,295 [salt.state       :1813][INFO    ][16109] Executing state module.run for [maas.wait_for_machine_status]
2019-06-02 04:59:30,296 [salt.utils.decorators:613 ][WARNING ][16109] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-02 04:59:33,401 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.90500402s left)
2019-06-02 04:59:40,409 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602045940396326
2019-06-02 04:59:40,432 [salt.minion      :1432][INFO    ][16137] Starting a new job with PID 16137
2019-06-02 04:59:40,454 [salt.minion      :1711][INFO    ][16137] Returning information for job: 20190602045940396326
2019-06-02 05:00:06,096 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.21007419s left)
2019-06-02 05:00:10,460 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050010447696
2019-06-02 05:00:10,482 [salt.minion      :1432][INFO    ][16155] Starting a new job with PID 16155
2019-06-02 05:00:10,507 [salt.minion      :1711][INFO    ][16155] Returning information for job: 20190602050010447696
2019-06-02 05:00:39,249 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.05768514s left)
2019-06-02 05:00:40,559 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050040546787
2019-06-02 05:00:40,581 [salt.minion      :1432][INFO    ][16211] Starting a new job with PID 16211
2019-06-02 05:00:40,604 [salt.minion      :1711][INFO    ][16211] Returning information for job: 20190602050040546787
2019-06-02 05:01:10,600 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050110588199
2019-06-02 05:01:10,621 [salt.minion      :1432][INFO    ][16329] Starting a new job with PID 16329
2019-06-02 05:01:10,644 [salt.minion      :1711][INFO    ][16329] Returning information for job: 20190602050110588199
2019-06-02 05:01:12,486 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.82021117s left)
2019-06-02 05:01:40,659 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050140646814
2019-06-02 05:01:40,682 [salt.minion      :1432][INFO    ][16814] Starting a new job with PID 16814
2019-06-02 05:01:40,709 [salt.minion      :1711][INFO    ][16814] Returning information for job: 20190602050140646814
2019-06-02 05:01:46,105 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.2009232s left)
2019-06-02 05:02:10,717 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050210704585
2019-06-02 05:02:10,735 [salt.minion      :1432][INFO    ][16841] Starting a new job with PID 16841
2019-06-02 05:02:10,747 [salt.minion      :1711][INFO    ][16841] Returning information for job: 20190602050210704585
2019-06-02 05:02:19,387 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.91879416s left)
2019-06-02 05:02:40,761 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050240748323
2019-06-02 05:02:40,785 [salt.minion      :1432][INFO    ][17402] Starting a new job with PID 17402
2019-06-02 05:02:40,810 [salt.minion      :1711][INFO    ][17402] Returning information for job: 20190602050240748323
2019-06-02 05:02:52,992 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.31475115s left)
2019-06-02 05:03:10,831 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050310819076
2019-06-02 05:03:10,854 [salt.minion      :1432][INFO    ][17423] Starting a new job with PID 17423
2019-06-02 05:03:10,879 [salt.minion      :1711][INFO    ][17423] Returning information for job: 20190602050310819076
2019-06-02 05:03:26,668 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.63814807s left)
2019-06-02 05:03:40,897 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050340885089
2019-06-02 05:03:40,920 [salt.minion      :1432][INFO    ][17513] Starting a new job with PID 17513
2019-06-02 05:03:40,944 [salt.minion      :1711][INFO    ][17513] Returning information for job: 20190602050340885089
2019-06-02 05:04:00,304 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.00258899s left)
2019-06-02 05:04:10,968 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050410956119
2019-06-02 05:04:10,992 [salt.minion      :1432][INFO    ][17533] Starting a new job with PID 17533
2019-06-02 05:04:11,016 [salt.minion      :1711][INFO    ][17533] Returning information for job: 20190602050410956119
2019-06-02 05:04:33,743 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.56340313s left)
2019-06-02 05:04:41,045 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050441032169
2019-06-02 05:04:41,068 [salt.minion      :1432][INFO    ][17964] Starting a new job with PID 17964
2019-06-02 05:04:41,093 [salt.minion      :1711][INFO    ][17964] Returning information for job: 20190602050441032169
2019-06-02 05:05:07,024 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.28179622s left)
2019-06-02 05:05:11,126 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050511114640
2019-06-02 05:05:11,149 [salt.minion      :1432][INFO    ][17990] Starting a new job with PID 17990
2019-06-02 05:05:11,179 [salt.minion      :1711][INFO    ][17990] Returning information for job: 20190602050511114640
2019-06-02 05:05:40,839 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.46751213s left)
2019-06-02 05:05:41,218 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050541205364
2019-06-02 05:05:41,242 [salt.minion      :1432][INFO    ][18414] Starting a new job with PID 18414
2019-06-02 05:05:41,264 [salt.minion      :1711][INFO    ][18414] Returning information for job: 20190602050541205364
2019-06-02 05:06:11,307 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050611294230
2019-06-02 05:06:11,328 [salt.minion      :1432][INFO    ][18457] Starting a new job with PID 18457
2019-06-02 05:06:11,348 [salt.minion      :1711][INFO    ][18457] Returning information for job: 20190602050611294230
2019-06-02 05:06:14,307 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.99948502s left)
2019-06-02 05:06:41,394 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050641382147
2019-06-02 05:06:41,417 [salt.minion      :1432][INFO    ][18814] Starting a new job with PID 18814
2019-06-02 05:06:41,442 [salt.minion      :1711][INFO    ][18814] Returning information for job: 20190602050641382147
2019-06-02 05:06:47,847 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.45978308s left)
2019-06-02 05:07:11,501 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050711487292
2019-06-02 05:07:11,524 [salt.minion      :1432][INFO    ][18835] Starting a new job with PID 18835
2019-06-02 05:07:11,548 [salt.minion      :1711][INFO    ][18835] Returning information for job: 20190602050711487292
2019-06-02 05:07:21,056 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1779.24993706s left)
2019-06-02 05:07:41,609 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050741599203
2019-06-02 05:07:41,631 [salt.minion      :1432][INFO    ][18992] Starting a new job with PID 18992
2019-06-02 05:07:41,653 [salt.minion      :1711][INFO    ][18992] Returning information for job: 20190602050741599203
2019-06-02 05:07:54,341 [salt.loaded.ext.module.maas:1023][INFO    ][16109] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1745.96555805s left)
2019-06-02 05:08:11,798 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command saltutil.find_job with jid 20190602050811785982
2019-06-02 05:08:11,822 [salt.minion      :1432][INFO    ][19137] Starting a new job with PID 19137
2019-06-02 05:08:11,845 [salt.minion      :1711][INFO    ][19137] Returning information for job: 20190602050811785982
2019-06-02 05:08:27,201 [salt.state       :300 ][INFO    ][16109] {'ret': True}
2019-06-02 05:08:27,201 [salt.state       :1951][INFO    ][16109] Completed state [maas.wait_for_machine_status] at time 05:08:27.201637 duration_in_ms=536906.435
2019-06-02 05:08:27,205 [salt.minion      :1711][INFO    ][16109] Returning information for job: 20190602045925344952
2019-06-02 05:44:09,185 [salt.utils.schedule:1377][INFO    ][12032] Running scheduled job: __mine_interval
2019-06-02 06:43:13,501 [salt.minion      :1308][INFO    ][12032] User sudo_ubuntu Executing command cp.push_dir with jid 20190602064313494228
2019-06-02 06:43:13,524 [salt.minion      :1432][INFO    ][26196] Starting a new job with PID 26196
