[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