Can't make it to work

[tardich]

Looks like a great puppet module. Unfortunately, despite all efforts I made, I can't make it to work. Updated the module to care about proxies (since I'm behind one), left all parameters to default (except for version which I set to 0.10.3), but I'm still getting this issue when trying to run:

Error: Could not retrieve catalog from remote server: Error 400 on SERVER: undefined method 'ref' for nil:NilClass on node

What do I do wrong?

[ferventcoder]

Perhaps a little more information? What does your manifest look like?

The error isn't one I've seen before, but that doesn't mean it isn't from this module.

So let's do this. Run the puppet agent or apply with debug, trace, and verbose and provide that output - e.g. puppet agent -t --trace --debug --verbose. That output will be more helpful in seeing where this error is occurring so we can pinpoint what needs to be done to fix the issue.

Second, what version of the module do you have installed? puppet module list will provide that information.

[tardich]

debug.txt

The attachment is the complete output of the puppet agent run with the parameters you requested.

About the manifest... well.... I'm managing my hosts through Foreman. So the only class included is chocolatey, and the only parameters set for the host are:

proxy_port: 3128
proxy_server: xxxxxxxxxxxxxx

Directly on the class, I have:

chocolatey_version: 0.10.3

Module version is 1.2.6

[ferventcoder]

The system cannot find the path specified.
Using Facter::Util::Resolution.exec with a shell built-in is deprecated. Most built-ins can be replaced with native ruby commands. If you really have to run a built-in, pass "cmd /c your_builtin" as a command (command responsible for this message was "vgs -o name --noheadings 2>/dev/null")
The system cannot find the path specified.
Using Facter::Util::Resolution.exec with a shell built-in is deprecated. Most built-ins can be replaced with native ruby commands. If you really have to run a built-in, pass "cmd /c your_builtin" as a command (command responsible for this message was "pvs -o name --noheadings 2>/dev/null")

[..snip..]

Using Facter::Util::Resolution.exec with a shell built-in is deprecated. Most built-ins can be replaced with native ruby commands. If you really have to run a built-in, pass "cmd /c your_builtin" as a command (command responsible for this message was "sshd -V 2>&1")
Debug: Failed to load library 'msgpack' for feature 'msgpack'�
Debug: Puppet::Network::Format[msgpack]: feature msgpack is missing�
Debug: catalog supports formats: pson b64_zlib_yaml yaml dot raw�
Error: Could not retrieve catalog from remote server: Error 400 on SERVER: undefined method `ref' for nil:NilClass on node [REDACTED]
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/indirector/rest.rb:194:in `is_http_200?'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/indirector/rest.rb:100:in `find'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/indirector/indirection.rb:201:in `find'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/configurer.rb:259:in `block in retrieve_new_catalog'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/util.rb:327:in `block in thinmark'
D:/Program Files (x86)/Puppet Labs/Puppet/sys/ruby/lib/ruby/1.9.1/benchmark.rb:295:in `realtime'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/util.rb:326:in `thinmark'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/configurer.rb:258:in `retrieve_new_catalog'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/configurer.rb:69:in `retrieve_catalog'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/configurer.rb:109:in `prepare_and_retrieve_catalog'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/configurer.rb:175:in `run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:48:in `block (4 levels) in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent/locker.rb:20:in `lock'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:48:in `block (3 levels) in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:118:in `with_client'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:45:in `block (2 levels) in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:83:in `run_in_fork'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:44:in `block in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:179:in `call'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:179:in `controlled_run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:42:in `run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application/agent.rb:355:in `onetime'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application/agent.rb:321:in `run_command'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:372:in `block (3 levels) in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:479:in `plugin_hook'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:372:in `block (2 levels) in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/util.rb:479:in `exit_on_fail'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:372:in `block in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/context.rb:51:in `override'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet.rb:233:in `override'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:362:in `run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/util/command_line.rb:137:in `run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/util/command_line.rb:91:in `execute'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/bin/puppet:4:in `<main>'�
Warning: Not using cache on failed catalog�
Error: Could not retrieve catalog; skipping run�

[ferventcoder]

@tardich these errors are not related to chocolatey/chocolatey from what I can tell. There may be something that having the Chocolatey module exasperates for something else, but not being able to retrieve the catalog isn't a known issue with using the module. You may want to remove Chocolatey and other components until you find out what is causing the issue with retrieving the catalog from the server.

Try applying anything else besides Chocolatey to the server and see if you get the same error.

Also what version of Puppet are you using?

[tardich]

Below is the log I created by changing the host environment, which is now in development rather than production. This, in fact, removed the question regarding the error we were seeing, related to lvm (vgs, pvs) and ssh (sshd) as I have almost no modules in this environment. Still the same problem, though. Still now working.

If I remove chocolatey module from the list of modules for my host, I don't have any issue on the run. So, somewhere along the line, chocolatey seems to be involved directly in the issue. Something weird missing on the puppet agent side?

I have two server environmens on which I'm testing this:

Windows 2012R2/Puppet 3.8.7
Windows 2008R2/Puppet 3.5.1

�[0;36mDebug: Failed to load library 'syslog' for feature 'syslog'�[0m
�[0;36mDebug: Failed to load library 'selinux' for feature 'selinux'�[0m
�[0;36mDebug: Using settings: adding file resource 'confdir': 'File[C:/ProgramData/PuppetLabs/puppet/etc]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'vardir': 'File[C:/ProgramData/PuppetLabs/puppet/var]{:path=>"C:/ProgramData/PuppetLabs/puppet/var", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'logdir': 'File[C:/ProgramData/PuppetLabs/puppet/var/log]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/log", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'statedir': 'File[C:/ProgramData/PuppetLabs/puppet/var/state]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state", :mode=>"1755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'rundir': 'File[C:/ProgramData/PuppetLabs/puppet/var/run]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/run", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'libdir': 'File[C:/ProgramData/PuppetLabs/puppet/var/lib]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/lib", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'certdir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'ssldir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl", :mode=>"771", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'publickeydir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'requestdir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certificate_requests]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certificate_requests", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'privatekeydir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'privatedir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/private", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'hostcert': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/bnhs06jb01.bellnhs.int.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/bnhs06jb01.bellnhs.int.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'hostprivkey': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys/bnhs06jb01.bellnhs.int.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys/bnhs06jb01.bellnhs.int.pem", :mode=>"640", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'hostpubkey': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys/bnhs06jb01.bellnhs.int.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys/bnhs06jb01.bellnhs.int.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'localcacert': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/ca.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/ca.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'hostcrl': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/crl.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/crl.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'statefile': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/state.yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/state.yaml", :mode=>"660", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'clientyamldir': 'File[C:/ProgramData/PuppetLabs/puppet/var/client_yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/client_yaml", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'client_datadir': 'File[C:/ProgramData/PuppetLabs/puppet/var/client_data]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/client_data", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'classfile': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/classes.txt]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/classes.txt", :mode=>"640", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'resourcefile': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/resources.txt]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/resources.txt", :mode=>"640", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'clientbucketdir': 'File[C:/ProgramData/PuppetLabs/puppet/var/clientbucket]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/clientbucket", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'lastrunfile': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/last_run_summary.yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/last_run_summary.yaml", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'lastrunreport': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/last_run_report.yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/last_run_report.yaml", :mode=>"640", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'graphdir': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/graphs]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/graphs", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'pluginfactdest': 'File[C:/ProgramData/PuppetLabs/puppet/var/facts.d]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/facts.d", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Puppet::Type::File::ProviderPosix: feature posix is missing�[0m
�[0;36mDebug: Failed to load library 'shadow' for feature 'libshadow'�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/log]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/state]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/run]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/lib]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certificate_requests]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/bnhs06jb01.bellnhs.int.pem]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys/bnhs06jb01.bellnhs.int.pem]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys/bnhs06jb01.bellnhs.int.pem]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/ca.pem]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/crl.pem]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/state/state.yaml]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var/state]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/client_yaml]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/client_data]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/state/classes.txt]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var/state]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/state/resources.txt]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var/state]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/clientbucket]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/state/last_run_summary.yaml]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var/state]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/state/last_run_report.yaml]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var/state]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/state/graphs]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var/state]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/facts.d]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: Finishing transaction 24371892�[0m
�[0;36mDebug: Using settings: adding file resource 'confdir': 'File[C:/ProgramData/PuppetLabs/puppet/etc]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'vardir': 'File[C:/ProgramData/PuppetLabs/puppet/var]{:path=>"C:/ProgramData/PuppetLabs/puppet/var", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'logdir': 'File[C:/ProgramData/PuppetLabs/puppet/var/log]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/log", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'statedir': 'File[C:/ProgramData/PuppetLabs/puppet/var/state]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state", :mode=>"1755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'rundir': 'File[C:/ProgramData/PuppetLabs/puppet/var/run]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/run", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'libdir': 'File[C:/ProgramData/PuppetLabs/puppet/var/lib]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/lib", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'certdir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'ssldir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl", :mode=>"771", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'publickeydir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'requestdir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certificate_requests]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certificate_requests", :mode=>"755", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'privatekeydir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'privatedir': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/private", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'hostcert': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/bnhs06jb01.bellnhs.int.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/bnhs06jb01.bellnhs.int.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'hostprivkey': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys/bnhs06jb01.bellnhs.int.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys/bnhs06jb01.bellnhs.int.pem", :mode=>"640", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'hostpubkey': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys/bnhs06jb01.bellnhs.int.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys/bnhs06jb01.bellnhs.int.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'localcacert': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/ca.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/ca.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'hostcrl': 'File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/crl.pem]{:path=>"C:/ProgramData/PuppetLabs/puppet/etc/ssl/crl.pem", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'pluginfactdest': 'File[C:/ProgramData/PuppetLabs/puppet/var/facts.d]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/facts.d", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/log]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/state]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/run]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/lib]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certificate_requests]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/bnhs06jb01.bellnhs.int.pem]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys/bnhs06jb01.bellnhs.int.pem]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/private_keys]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys/bnhs06jb01.bellnhs.int.pem]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/public_keys]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs/ca.pem]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/certs]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/etc/ssl/crl.pem]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/etc/ssl]�[0m
�[0;36mDebug: /File[C:/ProgramData/PuppetLabs/puppet/var/facts.d]: Autorequiring File[C:/ProgramData/PuppetLabs/puppet/var]�[0m
�[0;36mDebug: Finishing transaction 36824448�[0m
�[0;36mDebug: Using cached certificate for ca�[0m
�[0;36mDebug: Using cached certificate for bnhs06jb01.bellnhs.int�[0m
�[0;36mDebug: Using settings: adding file resource 'statefile': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/state.yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/state.yaml", :mode=>"660", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'clientyamldir': 'File[C:/ProgramData/PuppetLabs/puppet/var/client_yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/client_yaml", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'client_datadir': 'File[C:/ProgramData/PuppetLabs/puppet/var/client_data]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/client_data", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'classfile': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/classes.txt]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/classes.txt", :mode=>"640", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'resourcefile': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/resources.txt]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/resources.txt", :mode=>"640", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'clientbucketdir': 'File[C:/ProgramData/PuppetLabs/puppet/var/clientbucket]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/clientbucket", :mode=>"750", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'lastrunfile': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/last_run_summary.yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/last_run_summary.yaml", :mode=>"644", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'lastrunreport': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/last_run_report.yaml]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/last_run_report.yaml", :mode=>"640", :ensure=>:file, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Using settings: adding file resource 'graphdir': 'File[C:/ProgramData/PuppetLabs/puppet/var/state/graphs]{:path=>"C:/ProgramData/PuppetLabs/puppet/var/state/graphs", :ensure=>:directory, :loglevel=>:debug, :links=>:follow, :backup=>false}'�[0m
�[0;36mDebug: Finishing transaction 37422696�[0m
�[0;36mDebug: Loaded state in 0.05 seconds�[0m
�[0;36mDebug: Failed to load library 'msgpack' for feature 'msgpack'�[0m
�[0;36mDebug: Puppet::Network::Format[msgpack]: feature msgpack is missing�[0m
�[0;36mDebug: node supports formats: pson b64_zlib_yaml yaml raw�[0m
�[0;36mDebug: Using cached certificate for ca�[0m
�[0;36mDebug: Using cached certificate for bnhs06jb01.bellnhs.int�[0m
�[0;36mDebug: Using cached certificate_revocation_list for ca�[0m
�[0;32mInfo: Retrieving plugin�[0m
�[0;36mDebug: Failed to load library 'msgpack' for feature 'msgpack'�[0m
�[0;36mDebug: Puppet::Network::Format[msgpack]: feature msgpack is missing�[0m
�[0;36mDebug: file_metadata supports formats: pson b64_zlib_yaml yaml raw�[0m
�[0;36mDebug: Finishing transaction 24250428�[0m
�[0;32mInfo: Loading facts in C:/ProgramData/PuppetLabs/puppet/var/lib/facter/chocolateyversion.rb�[0m
�[0;32mInfo: Loading facts in C:/ProgramData/PuppetLabs/puppet/var/lib/facter/choco_install_path.rb�[0m
�[0;36mDebug: Failed to load library 'msgpack' for feature 'msgpack'�[0m
�[0;36mDebug: Puppet::Network::Format[msgpack]: feature msgpack is missing�[0m
�[0;36mDebug: catalog supports formats: pson b64_zlib_yaml yaml dot raw�[0m
�[1;31mError: Could not retrieve catalog from remote server: Error 400 on SERVER: undefined method `ref' for nil:NilClass on node [REDACTED]
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/indirector/rest.rb:194:in `is_http_200?'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/indirector/rest.rb:100:in `find'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/indirector/indirection.rb:201:in `find'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/configurer.rb:259:in `block in retrieve_new_catalog'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/util.rb:327:in `block in thinmark'
D:/Program Files (x86)/Puppet Labs/Puppet/sys/ruby/lib/ruby/1.9.1/benchmark.rb:295:in `realtime'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/util.rb:326:in `thinmark'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/configurer.rb:258:in `retrieve_new_catalog'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/configurer.rb:69:in `retrieve_catalog'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/configurer.rb:109:in `prepare_and_retrieve_catalog'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/configurer.rb:175:in `run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:48:in `block (4 levels) in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent/locker.rb:20:in `lock'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:48:in `block (3 levels) in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:118:in `with_client'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:45:in `block (2 levels) in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:83:in `run_in_fork'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:44:in `block in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:179:in `call'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:179:in `controlled_run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/agent.rb:42:in `run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application/agent.rb:355:in `onetime'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application/agent.rb:321:in `run_command'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:372:in `block (3 levels) in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:479:in `plugin_hook'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:372:in `block (2 levels) in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/util.rb:479:in `exit_on_fail'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:372:in `block in run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/context.rb:51:in `override'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet.rb:233:in `override'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/application.rb:362:in `run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/util/command_line.rb:137:in `run'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/lib/puppet/util/command_line.rb:91:in `execute'
D:/Program Files (x86)/Puppet Labs/Puppet/puppet/bin/puppet:4:in `<main>'�[0m
�[1;31mWarning: Not using cache on failed catalog�[0m
�[1;31mError: Could not retrieve catalog; skipping run�[0m

[tardich]

Just performed a successful install in the lab environment where the main difference is..... I have no proxy !!! Direct internet access there. chocolatey 1.2.6, puppet 3.8.7, Windows 2008R2.

So it really looks like it's not working with a proxy in front of the server, which is unfortunate, as all of my prod environment sits behind web proxies

[ferventcoder]

@tardich the good thing about Chocolatey is that you can host everything internally. It does seem like something about the Chocolatey module is influencing something within your Puppet run and removing its ability to work through the proxy.

[ferventcoder]

It does work with web proxies normally, maybe we could explore the proxy type that you are using to see if there is some incompatibility there?

[ferventcoder]

That way we can fix the problem once we identify what it is.