[DEBUG ] Reading configuration from /etc/salt/minion [DEBUG ] Including configuration from '/etc/salt/minion.d/_schedule.conf' [DEBUG ] Reading configuration from /etc/salt/minion.d/_schedule.conf [DEBUG ] Using cached minion ID from /etc/salt/minion_id: zoso2.unaen.org [DEBUG ] Configuration file path: /etc/salt/minion [WARNING ] Insecure logging configuration detected! Sensitive data may be logged. [DEBUG ] Grains refresh requested. Refreshing grains. [DEBUG ] Reading configuration from /etc/salt/minion [DEBUG ] Including configuration from '/etc/salt/minion.d/_schedule.conf' [DEBUG ] Reading configuration from /etc/salt/minion.d/_schedule.conf [DEBUG ] Please install 'virt-what' to improve results of the 'virtual' grain. [DEBUG ] Loading static grains from /etc/salt/grains [INFO ] Executing command '/usr/sbin/mdata-list' in directory '/root' [DEBUG ] output: [INFO ] Executing command '/usr/sbin/mdata-get sdc:uuid' in directory '/root' [DEBUG ] output: f75ac9ab-4f56-6852-eb7b-aef755e541de [DEBUG ] found mdata entry sdc:uuid with value f75ac9ab-4f56-6852-eb7b-aef755e541de [INFO ] Executing command '/usr/sbin/mdata-get sdc:server_uuid' in directory '/root' [DEBUG ] output: 534d4349-0002-4a90-2500-4a902500bcb8 [DEBUG ] found mdata entry sdc:server_uuid with value 534d4349-0002-4a90-2500-4a902500bcb8 [INFO ] Executing command '/usr/sbin/mdata-get sdc:datacenter_name' in directory '/root' [ERROR ] Command '/usr/sbin/mdata-get sdc:datacenter_name' failed with return code: 1 [ERROR ] output: No metadata for 'sdc:datacenter_name' [INFO ] Executing command '/usr/sbin/mdata-get sdc:hostname' in directory '/root' [DEBUG ] output: zoso2 [DEBUG ] found mdata entry sdc:hostname with value zoso2 [INFO ] Executing command '/usr/sbin/mdata-get sdc:dns_domain' in directory '/root' [DEBUG ] output: unaen.org [DEBUG ] found mdata entry sdc:dns_domain with value unaen.org [INFO ] Executing command '/usr/sbin/mdata-get sdc:resolvers' in directory '/root' [DEBUG ] output: ["198.60.22.2","198.60.22.22"] [DEBUG ] found mdata entry sdc:resolvers with value ["198.60.22.2","198.60.22.22"] [INFO ] Executing command '/usr/sbin/mdata-get sdc:nics' in directory '/root' [DEBUG ] output: [{"interface":"eth0","mac":"12:bc:0f:7e:54:9c","nic_tag":"admin","gateway":"166.70.129.193","gateways":["166.70.129.193"],"netmask":"255.255.255.224","ip":"166.70.129.213","ips":["166.70.129.213/27","2607:fa18:3:1337:10bc:0fff:fe7e:549c/64"],"allowed_ips":["fe80::10bc:0fff:fe7e:549c"],"primary":true}] [DEBUG ] found mdata entry sdc:nics with value [{"interface":"eth0","mac":"12:bc:0f:7e:54:9c","nic_tag":"admin","gateway":"166.70.129.193","gateways":["166.70.129.193"],"netmask":"255.255.255.224","ip":"166.70.129.213","ips":["166.70.129.213/27","2607:fa18:3:1337:10bc:0fff:fe7e:549c/64"],"allowed_ips":["fe80::10bc:0fff:fe7e:549c"],"primary":true}] [INFO ] Executing command '/usr/sbin/mdata-get sdc:routes' in directory '/root' [DEBUG ] output: [] [DEBUG ] found mdata entry sdc:routes with value [] [DEBUG ] Connecting to master. Attempt 1 of 1 [DEBUG ] Master URI: tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506 [DEBUG ] Initializing new AsyncAuth for (u'/etc/salt/pki/minion', u'zoso2.unaen.org', u'tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506') [DEBUG ] Generated random reconnect delay between '1000ms' and '11000ms' (5923) [DEBUG ] Setting zmq_reconnect_ivl to '5923ms' [DEBUG ] Setting zmq_reconnect_ivl_max to '11000ms' [DEBUG ] Initializing new AsyncZeroMQReqChannel for (u'/etc/salt/pki/minion', u'zoso2.unaen.org', u'tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506', 'clear') [DEBUG ] Connecting the Minion to the Master URI (for the return server): tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506 [DEBUG ] Trying to connect to: tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506 [DEBUG ] salt.crypt.get_rsa_pub_key: Loading public key [DEBUG ] Decrypting the current master AES key [DEBUG ] salt.crypt.get_rsa_key: Loading private key [DEBUG ] salt.crypt._get_key_with_evict: Loading private key [DEBUG ] Loaded minion key: /etc/salt/pki/minion/minion.pem [DEBUG ] salt.crypt.get_rsa_pub_key: Loading public key [DEBUG ] Connecting the Minion to the Master publish port, using the URI: tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4505 [DEBUG ] salt.crypt.get_rsa_key: Loading private key [DEBUG ] Loaded minion key: /etc/salt/pki/minion/minion.pem [DEBUG ] Determining pillar cache [DEBUG ] Initializing new AsyncZeroMQReqChannel for (u'/etc/salt/pki/minion', u'zoso2.unaen.org', u'tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506', u'aes') [DEBUG ] Initializing new AsyncAuth for (u'/etc/salt/pki/minion', u'zoso2.unaen.org', u'tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506') [DEBUG ] Connecting the Minion to the Master URI (for the return server): tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506 [DEBUG ] Trying to connect to: tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506 [DEBUG ] salt.crypt.get_rsa_key: Loading private key [DEBUG ] Loaded minion key: /etc/salt/pki/minion/minion.pem [DEBUG ] LazyLoaded jinja.render [DEBUG ] LazyLoaded yaml.render [DEBUG ] LazyLoaded state.sls [DEBUG ] LazyLoaded saltutil.is_running [DEBUG ] LazyLoaded grains.get [DEBUG ] LazyLoaded config.get [DEBUG ] Initializing new AsyncZeroMQReqChannel for (u'/etc/salt/pki/minion', u'zoso2.unaen.org', u'tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506', u'aes') [DEBUG ] Initializing new AsyncAuth for (u'/etc/salt/pki/minion', u'zoso2.unaen.org', u'tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506') [DEBUG ] Connecting the Minion to the Master URI (for the return server): tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506 [DEBUG ] Trying to connect to: tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506 [INFO ] Loading fresh modules for state activity [DEBUG ] LazyLoaded jinja.render [DEBUG ] LazyLoaded yaml.render [DEBUG ] In saltenv 'base', looking at rel_path 'test/grains.sls' to resolve 'salt://test/grains.sls' [DEBUG ] In saltenv 'base', ** considering ** path '/var/cache/salt/minion/files/base/test/grains.sls' to resolve 'salt://test/grains.sls' [DEBUG ] compile template: /var/cache/salt/minion/files/base/test/grains.sls [DEBUG ] Jinja search path: [u'/var/cache/salt/minion/files/base'] [DEBUG ] Initializing new AsyncZeroMQReqChannel for (u'/etc/salt/pki/minion', u'zoso2.unaen.org', u'tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506', u'aes') [DEBUG ] Initializing new AsyncAuth for (u'/etc/salt/pki/minion', u'zoso2.unaen.org', u'tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506') [DEBUG ] Connecting the Minion to the Master URI (for the return server): tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506 [DEBUG ] Trying to connect to: tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506 [PROFILE ] Time (in seconds) to render '/var/cache/salt/minion/files/base/test/grains.sls' using 'jinja' renderer: 0.0181632041931 [DEBUG ] Rendered data from file: /var/cache/salt/minion/files/base/test/grains.sls: blah: grains.present: - value: bleh [DEBUG ] Results of YAML rendering: OrderedDict([(u'blah', OrderedDict([(u'grains.present', [OrderedDict([(u'value', u'bleh')])])]))]) [PROFILE ] Time (in seconds) to render '/var/cache/salt/minion/files/base/test/grains.sls' using 'yaml' renderer: 0.00199103355408 [DEBUG ] LazyLoaded config.option [DEBUG ] LazyLoaded grains.present [INFO ] Running state [blah] at time 23:30:46.139842 [INFO ] Executing state grains.present for [blah] [DEBUG ] LazyLoaded grains.get [INFO ] {u'new': u'blah'} [INFO ] Completed state [blah] at time 23:30:46.141910 (duration_in_ms=2.068) [DEBUG ] File /var/cache/salt/minion/accumulator/140737131392976 does not exist, no need to cleanup [DEBUG ] LazyLoaded state.check_result [DEBUG ] Initializing new AsyncZeroMQReqChannel for (u'/etc/salt/pki/minion', u'zoso2.unaen.org', u'tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506', u'aes') [DEBUG ] Initializing new AsyncAuth for (u'/etc/salt/pki/minion', u'zoso2.unaen.org', u'tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506') [DEBUG ] Connecting the Minion to the Master URI (for the return server): tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506 [DEBUG ] Trying to connect to: tcp://[2607:fa18:3:1337:c0db:5dff:fe6f:78b1]:4506 [DEBUG ] LazyLoaded highstate.output [DEBUG ] LazyLoaded nested.output local: ---------- ID: blah Function: grains.present Result: None Comment: Grain blah is set to be added Started: 23:30:46.139842 Duration: 2.068 ms Changes: ---------- new: blah Summary for local ------------ Succeeded: 1 (unchanged=1, changed=1) Failed: 0 ------------ Total states run: 1 Total run time: 2.068 ms