VM issue: Chef never successfully completed

[ezehalac]

I keep running into this issue... "Chef never successfully completed! " I have read every post here.. tried every combination of vagrant ssh commands, halts, reloads, destroys, downgraded to vagrant 171, etc etc! I have a macbook pro, running Yosemite 10.10.1, Vagrant 1.7.1, Virtualbox 4.3.20.. I followed the instructions on the video STEP by STEP. Please help! I have spent several hours trying to troubleshoot this. See below:

Bringing machine 'default' up with 'virtualbox' provider... ==> default: Importing base box 'precise64'... ==> default: Matching MAC address for NAT networking... ==> default: Setting the name of the VM: Mining-the-Social-Web-2nd-Edition_default_1423603782195_61233 ==> default: Clearing any previously set network interfaces... ==> default: Preparing network interfaces based on configuration... default: Adapter 1: nat ==> default: Forwarding ports... default: 8888 => 8888 (adapter 1) default: 5000 => 5000 (adapter 1) default: 27017 => 27017 (adapter 1) default: 27018 => 27018 (adapter 1) default: 27019 => 27019 (adapter 1) default: 28017 => 28017 (adapter 1) default: 22 => 2222 (adapter 1) ==> default: Running 'pre-boot' VM customizations... ==> default: Booting VM... ==> default: Waiting for machine to boot. This may take a few minutes... default: SSH address: 127.0.0.1:2222 default: SSH username: vagrant default: SSH auth method: private key default: default: Vagrant insecure key detected. Vagrant will automatically replace default: this with a newly generated keypair for better security. default: default: Inserting generated public key within guest... default: Removing insecure key from the guest if its present... default: Key inserted! Disconnecting and reconnecting using new SSH key... ==> default: Machine booted and ready! ==> default: Checking for guest additions in VM... default: The guest additions on this VM do not match the installed version of default: VirtualBox! In most cases this is fine, but in rare cases it can default: prevent things such as shared folders from working properly. If you see default: shared folder errors, please make sure the guest additions within the default: virtual machine match the version of VirtualBox you have installed on default: your host and reload your VM. default: default: Guest Additions Version: 4.2.0 default: VirtualBox Version: 4.3 ==> default: Mounting shared folders... default: /vagrant => /Users/ezehalac/Mining-the-Social-Web-2nd-Edition default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks => /Users/ezehalac/Mining-the-Social-Web-2nd-Edition/deploy/cookbooks ==> default: Running provisioner: chef_solo... default: Installing Chef (latest)... Generating chef JSON and uploading... ==> default: Running chef-solo... ==> default: stdin: is not a tty ==> default: [2015-02-10T21:30:22+00:00] INFO: Forking chef instance to converge... ==> default: [2015-02-10T21:30:22+00:00] DEBUG: Fork successful. Waiting for new chef pid: 1632 ==> default: [2015-02-10T21:30:22+00:00] DEBUG: Forked instance now converging ==> default: [2015-02-10T21:30:22+00:00] INFO: * Chef 12.0.3 * ==> default: [2015-02-10T21:30:22+00:00] INFO: Chef-client pid: 1632 ==> default: [2015-02-10T21:30:22+00:00] DEBUG: Chef-client request_id: 747455c4-1e8b-45b9-84fd-b43bb5a83f78 ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Building node object for precise64 ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Extracting run list from JSON attributes provided on command line ==> default: [2015-02-10T21:30:23+00:00] INFO: Setting the run_list to ["recipe[mtsw2e::default]"] from CLI options ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Applying attributes from json file ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Platform is ubuntu version 12.04 ==> default: [2015-02-10T21:30:23+00:00] INFO: Run List is [recipe[mtsw2e::default]] ==> default: [2015-02-10T21:30:23+00:00] INFO: Run List expands to [mtsw2e::default] ==> default: [2015-02-10T21:30:23+00:00] INFO: Starting Chef Run for precise64 ==> default: [2015-02-10T21:30:23+00:00] INFO: Running start handlers ==> default: [2015-02-10T21:30:23+00:00] INFO: Start handlers complete. ==> default: [2015-02-10T21:30:23+00:00] DEBUG: No chefignore file found at /tmp/vagrant-chef-6/chef-solo-1/chefignore no files will be ignored ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Cookbooks to compile: [:apt, :"build-essential", :yum, :python, :runit, :mtsw2e] ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook runit's library file: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/libraries/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook runit's library file: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/libraries/provider_runit_service.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook runit's library file: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/libraries/resource_runit_service.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook apt's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/attributes/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute apt::default ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook build-essential's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/build-essential/attributes/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute build-essential::default ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/attributes/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute yum::default ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/attributes/elrepo.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute yum::elrepo ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/attributes/epel.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute yum::epel ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/attributes/remi.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute yum::remi ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook python's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/attributes/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute python::default ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook runit's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/attributes/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute runit::default ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook apt's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/providers/preference.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/providers/preference.rb into a provider named apt_preference defined in Chef::Provider::AptPreference ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook apt's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/providers/repository.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/providers/repository.rb into a provider named apt_repository defined in Chef::Provider::AptRepository ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook apt's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/resources/preference.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/resources/preference.rb into a resource named apt_preference defined in Chef::Resource::AptPreference ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook apt's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/resources/repository.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/resources/repository.rb into a resource named apt_repository defined in Chef::Resource::AptRepository ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook yum's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/providers/key.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/providers/key.rb into a provider named yum_key defined in Chef::Provider::YumKey ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook yum's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/providers/repository.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/providers/repository.rb into a provider named yum_repository defined in Chef::Provider::YumRepository ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook yum's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/resources/key.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/resources/key.rb into a resource named yum_key defined in Chef::Resource::YumKey ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook yum's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/resources/repository.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/resources/repository.rb into a resource named yum_repository defined in Chef::Resource::YumRepository ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook python's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/providers/pip.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/providers/pip.rb into a provider named python_pip defined in Chef::Provider::PythonPip ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook python's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/providers/virtualenv.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/providers/virtualenv.rb into a provider named python_virtualenv defined in Chef::Provider::PythonVirtualenv ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook python's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/resources/pip.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/resources/pip.rb into a resource named python_pip defined in Chef::Resource::PythonPip ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook python's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/resources/virtualenv.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/resources/virtualenv.rb into a resource named python_virtualenv defined in Chef::Resource::PythonVirtualenv ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Recipe mtsw2e::default via include_recipe ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Found recipe default in cookbook mtsw2e ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Recipe apt via include_recipe ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Found recipe default in cookbook apt ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe runit via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Found recipe default in cookbook runit ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Caught InvalidVersionConstraint. This means that a key in value_for_platform cannot be interpreted as a Chef::VersionConstraint. ==> default: [2015-02-10T21:30:24+00:00] DEBUG: 'default' (Chef::Exceptions::InvalidVersionConstraint) ==> default: /opt/chef/embedded/apps/chef/lib/chef/version_constraint.rb:113:in parse' ==> default: /opt/chef/embedded/apps/chef/lib/chef/version_constraint.rb:36:ininitialize' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:79:in new' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:79:inblock in match_versions' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:77:in each' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:77:inmatch_versions' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:54:in value_for_node' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:149:invalue_for_platform' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/recipes/default.rb:25:in block in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:120:ininstance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:120:in build_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:82:indeclare_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:42:in method_missing' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/recipes/default.rb:24:infrom_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:in instance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:infrom_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/cookbook_version.rb:245:in load_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:169:inload_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:138:in block in include_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:137:ineach' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:137:in include_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/include_recipe.rb:26:ininclude_recipe' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/mtsw2e/recipes/default.rb:3:in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:ininstance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/cookbook_version.rb:245:inload_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:169:in load_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:140:inblock in compile_recipes' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:138:in each' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:138:incompile_recipes' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:75:in compile' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:92:inload' ==> default: /opt/chef/embedded/apps/chef/lib/chef/policy_builder/expand_node_object.rb:73:in setup_run_context' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:235:insetup_run_context' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:397:in run' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:261:inblock in fork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:in fork' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:infork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:215:in block in run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/local_mode.rb:38:inwith_server_connectivity' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:201:in run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:245:inblock in interval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:in loop' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:ininterval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:224:in run_application' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:58:inrun' ==> default: /opt/chef/embedded/apps/chef/bin/chef-solo:25:in <top (required)>' ==> default: /usr/bin/chef-solo:40:inload' ==> default: /usr/bin/chef-solo:40:in

' ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Caught InvalidVersionConstraint. This means that a key in value_for_platform cannot be interpreted as a Chef::VersionConstraint. ==> default: [2015-02-10T21:30:24+00:00] DEBUG: 'default' (Chef::Exceptions::InvalidVersionConstraint) ==> default: /opt/chef/embedded/apps/chef/lib/chef/version_constraint.rb:113:inparse' ==> default: /opt/chef/embedded/apps/chef/lib/chef/version_constraint.rb:36:in initialize' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:79:innew' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:79:in block in match_versions' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:77:ineach' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:77:in match_versions' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:54:invalue_for_node' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:149:in value_for_platform' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/recipes/default.rb:85:inblock in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:120:in instance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:120:inbuild_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:82:in declare_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:42:inmethod_missing' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/recipes/default.rb:80:in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:ininstance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/cookbook_version.rb:245:inload_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:169:in load_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:138:inblock in include_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:137:in each' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:137:ininclude_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/include_recipe.rb:26:in include_recipe' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/mtsw2e/recipes/default.rb:3:infrom_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:in instance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:infrom_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/cookbook_version.rb:245:in load_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:169:inload_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:140:in block in compile_recipes' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:138:ineach' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:138:in compile_recipes' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:75:incompile' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:92:in load' ==> default: /opt/chef/embedded/apps/chef/lib/chef/policy_builder/expand_node_object.rb:73:insetup_run_context' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:235:in setup_run_context' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:397:inrun' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:261:in block in fork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:infork' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:in fork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:215:inblock in run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/local_mode.rb:38:in with_server_connectivity' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:201:inrun_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:245:in block in interval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:inloop' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:in interval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:224:inrun_application' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:58:in run' ==> default: /opt/chef/embedded/apps/chef/bin/chef-solo:25:in' ==> default: /usr/bin/chef-solo:40:in load' ==> default: /usr/bin/chef-solo:40:in' ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe python via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Found recipe default in cookbook python ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe python::package via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Found recipe package in cookbook python ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe python::pip via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Found recipe pip in cookbook python ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe python::virtualenv via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Found recipe virtualenv in cookbook python ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe python::pip via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: I am not loading python::pip, because I have already seen it. ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading from cookbook_path: /tmp/vagrant-chef-6/chef-solo-1/cookbooks ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Converging node precise64 ==> default: [2015-02-10T21:30:24+00:00] INFO: Processing execute[apt-get-update] action run (apt::default line 22) ==> default: [2015-02-10T21:30:24+00:00] DEBUG: providers for generic execute resource enabled on node include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:24+00:00] DEBUG: providers that refused resource execute[apt-get-update] were: [] ==> default: [2015-02-10T21:30:24+00:00] DEBUG: providers that support resource execute[apt-get-update] include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:24+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:27+00:00] INFO: execute[apt-get-update] ran successfully ==> default: [2015-02-10T21:30:27+00:00] INFO: Processing execute[apt-get update] action nothing (apt::default line 29) ==> default: [2015-02-10T21:30:27+00:00] DEBUG: Skipping execute[apt-get update] due to action :nothing ==> default: [2015-02-10T21:30:27+00:00] INFO: Processing execute[apt-get autoremove] action nothing (apt::default line 36) ==> default: [2015-02-10T21:30:27+00:00] DEBUG: Skipping execute[apt-get autoremove] due to action :nothing ==> default: [2015-02-10T21:30:27+00:00] INFO: Processing execute[apt-get autoclean] action nothing (apt::default line 42) ==> default: [2015-02-10T21:30:27+00:00] DEBUG: Skipping execute[apt-get autoclean] due to action :nothing ==> default: [2015-02-10T21:30:27+00:00] INFO: Processing apt_package[update-notifier-common] action install (apt::default line 48) ==> default: [2015-02-10T21:30:27+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:27+00:00] DEBUG: providers that refused resource apt_package[update-notifier-common] were: [] ==> default: [2015-02-10T21:30:27+00:00] DEBUG: providers that support resource apt_package[update-notifier-common] include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:27+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:27+00:00] DEBUG: apt_package[update-notifier-common] checking package status for update-notifier-common ==> default: [2015-02-10T21:30:27+00:00] DEBUG: apt_package[update-notifier-common] current version is nil ==> default: [2015-02-10T21:30:27+00:00] DEBUG: apt_package[update-notifier-common] candidate version is 0.119ubuntu8.7 ==> default: [2015-02-10T21:30:34+00:00] INFO: apt_package[update-notifier-common] sending run action to executeapt-get-update ==> default: [2015-02-10T21:30:34+00:00] INFO: Processing execute[apt-get-update] action run (apt::default line 22) ==> default: [2015-02-10T21:30:34+00:00] DEBUG: providers for generic execute resource enabled on node include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:34+00:00] DEBUG: providers that refused resource execute[apt-get-update] were: [] ==> default: [2015-02-10T21:30:34+00:00] DEBUG: providers that support resource execute[apt-get-update] include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:34+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:37+00:00] INFO: execute[apt-get-update] ran successfully ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing execute[apt-get-update-periodic] action run (apt::default line 52) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Skipping execute[apt-get-update-periodic] due to only_if ruby block ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing directory[/var/cache/local] action create (apt::default line 62) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers for generic directory resource enabled on node include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that refused resource directory[/var/cache/local] were: [] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that support resource directory[/var/cache/local] include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local] created directory /var/cache/local ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local] owner changed to 0 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local] group changed to 0 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local] mode changed to 755 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing directory[/var/cache/local/preseeding] action create (apt::default line 62) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers for generic directory resource enabled on node include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that refused resource directory[/var/cache/local/preseeding] were: [] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that support resource directory[/var/cache/local/preseeding] include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local/preseeding] created directory /var/cache/local/preseeding ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local/preseeding] owner changed to 0 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local/preseeding] group changed to 0 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local/preseeding] mode changed to 755 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing service[runit] action nothing (runit::default line 20) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Skipping service[runit] due to action :nothing ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing execute[start-runsvdir] action nothing (runit::default line 24) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Skipping execute[start-runsvdir] due to action :nothing ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing execute[runit-hup-init] action nothing (runit::default line 33) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Skipping execute[runit-hup-init] due to action :nothing ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing apt_package[runit] action install (runit::default line 80) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that refused resource apt_package[runit] were: [] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that support resource apt_package[runit] include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] checking package status for runit ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] current version is nil ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] candidate version is 2.1.1-6.2ubuntu2.1 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Creating directory /var/chef/cache/preseed ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Creating directory /var/chef/cache/preseed/runit ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] fetching preseed file to /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] fetching preseed file via cookbook_file ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing cookbook_file[/var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed] action create (dynamically defined) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: cookbook_file[/var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed] staging /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/files/default/runit.seed to /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150210-1632-tqv74r ==> default: [2015-02-10T21:30:37+00:00] DEBUG: touching /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed to create it ==> default: [2015-02-10T21:30:37+00:00] INFO: cookbook_file[/var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed] created file /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed ==> default: [2015-02-10T21:30:37+00:00] DEBUG: calculating checksum of /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150210-1632-tqv74r to compare with ==> default: [2015-02-10T21:30:37+00:00] DEBUG: running: diff -u /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150210-1632-tqv74r ==> default: [2015-02-10T21:30:37+00:00] DEBUG: reading modes from /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed file ==> default: [2015-02-10T21:30:37+00:00] DEBUG: applying mode = 644, uid = 0, gid = 0 to /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150210-1632-tqv74r ==> default: [2015-02-10T21:30:37+00:00] DEBUG: moving temporary file /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150210-1632-tqv74r into place at /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed ==> default: [2015-02-10T21:30:37+00:00] INFO: cookbook_file[/var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed] updated file contents /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found target_mode == nil, so no mode was specified on resource, not managing mode ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-02-10T21:30:37+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] fetched preseed file to /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed ==> default: [2015-02-10T21:30:37+00:00] INFO: apt_package[runit] pre-seeding package installation instructions ==> default: [2015-02-10T21:30:40+00:00] INFO: apt_package[runit] sending nothing action to executestart-runsvdir ==> default: [2015-02-10T21:30:40+00:00] INFO: Processing execute[start-runsvdir] action nothing (runit::default line 24) ==> default: [2015-02-10T21:30:40+00:00] DEBUG: Skipping execute[start-runsvdir] due to action :nothing ==> default: [2015-02-10T21:30:40+00:00] INFO: apt_package[runit] sending nothing action to executerunit-hup-init ==> default: [2015-02-10T21:30:40+00:00] INFO: Processing execute[runit-hup-init] action nothing (runit::default line 33) ==> default: [2015-02-10T21:30:40+00:00] DEBUG: Skipping execute[runit-hup-init] due to action :nothing ==> default: [2015-02-10T21:30:40+00:00] INFO: Processing apt_package[python] action install (python::package line 40) ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that refused resource apt_package[python] were: [] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that support resource apt_package[python] include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python] checking package status for python ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python] current version is 2.7.3-0ubuntu2 ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python] candidate version is 2.7.3-0ubuntu2.2 ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python] is already installed - nothing to do ==> default: [2015-02-10T21:30:40+00:00] INFO: Processing apt_package[python-dev] action install (python::package line 40) ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that refused resource apt_package[python-dev] were: [] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that support resource apt_package[python-dev] include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python-dev] checking package status for python-dev ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python-dev] current version is nil ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python-dev] candidate version is 2.7.3-0ubuntu2.2 ==> default: [2015-02-10T21:30:52+00:00] INFO: Processing remote_file[/var/chef/cache/ez_setup.py] action create (python::pip line 36) ==> default: [2015-02-10T21:30:52+00:00] DEBUG: remote_file[/var/chef/cache/ez_setup.py] checking for changes ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Cache control headers: {} ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Initiating GET to https://bitbucket.org/pypa/setuptools/raw/0.8/ez_setup.py ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- HTTP Request Header Data: ---- ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- End HTTP Request Header Data ---- ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- HTTP Status and Header Data: ---- ==> default: [2015-02-10T21:30:52+00:00] DEBUG: HTTP 1.1 200 OK ==> default: [2015-02-10T21:30:52+00:00] DEBUG: server: nginx/1.5.10 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: date: Tue, 10 Feb 2015 21:30:53 GMT ==> default: [2015-02-10T21:30:52+00:00] DEBUG: content-type: text/plain ==> default: [2015-02-10T21:30:52+00:00] DEBUG: transfer-encoding: chunked ==> default: [2015-02-10T21:30:52+00:00] DEBUG: connection: close ==> default: [2015-02-10T21:30:52+00:00] DEBUG: vary: Accept-Language, Cookie ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-served-by: app16 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-render-time: 0.0463080406189 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: content-language: en ==> default: [2015-02-10T21:30:52+00:00] DEBUG: cache-control: max-age=0 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-request-count: 70 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: expires: Tue, 10 Feb 2015 21:30:53 GMT ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-frame-options: SAMEORIGIN ==> default: [2015-02-10T21:30:52+00:00] DEBUG: last-modified: Tue, 10 Feb 2015 21:30:53 GMT ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-static-version: dac75071f136 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-version: dac75071f136 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: strict-transport-security: max-age=31536000 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-content-type-options: nosniff ==> default: [2015-02-10T21:30:52+00:00] DEBUG: content-encoding: gzip ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- End HTTP Status/Header Data ---- ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Streaming download from https://bitbucket.org/pypa/setuptools/raw/0.8/ez_setup.py to tempfile /tmp/chef-rest20150210-1632-rtulqr ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Initializing gzip stream deflator ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_stream_complete ==> default: [2015-02-10T21:30:52+00:00] DEBUG: HTTP server did not include a Content-Length header in response, cannot identify truncated downloads. ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_stream_complete ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_stream_complete ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Creating directory /var/chef/cache/remote_file ==> default: [2015-02-10T21:30:52+00:00] DEBUG: touching /var/chef/cache/ez_setup.py to create it ==> default: [2015-02-10T21:30:52+00:00] INFO: remote_file[/var/chef/cache/ez_setup.py] created file /var/chef/cache/ez_setup.py ==> default: [2015-02-10T21:30:52+00:00] DEBUG: calculating checksum of /tmp/chef-rest20150210-1632-rtulqr to compare with ==> default: [2015-02-10T21:30:52+00:00] DEBUG: running: diff -u /var/chef/cache/ez_setup.py /tmp/chef-rest20150210-1632-rtulqr ==> default: [2015-02-10T21:30:52+00:00] DEBUG: reading modes from /var/chef/cache/ez_setup.py file ==> default: [2015-02-10T21:30:52+00:00] DEBUG: applying mode = 644, uid = 0, gid = 0 to /tmp/chef-rest20150210-1632-rtulqr ==> default: [2015-02-10T21:30:52+00:00] DEBUG: moving temporary file /tmp/chef-rest20150210-1632-rtulqr into place at /var/chef/cache/ez_setup.py ==> default: [2015-02-10T21:30:52+00:00] INFO: remote_file[/var/chef/cache/ez_setup.py] updated file contents /var/chef/cache/ez_setup.py ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:52+00:00] INFO: remote_file[/var/chef/cache/ez_setup.py] mode changed to 644 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-02-10T21:30:52+00:00] INFO: Processing remote_file[/var/chef/cache/get-pip.py] action create (python::pip line 42) ==> default: [2015-02-10T21:30:52+00:00] DEBUG: remote_file[/var/chef/cache/get-pip.py] checking for changes ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Cache control headers: {} ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Initiating GET to https://raw.github.com/pypa/pip/master/contrib/get-pip.py ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- HTTP Request Header Data: ---- ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- End HTTP Request Header Data ---- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- HTTP Status and Header Data: ---- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: HTTP 1.1 301 Moved Permanently ==> default: [2015-02-10T21:30:53+00:00] DEBUG: date: Tue, 10 Feb 2015 21:30:54 GMT ==> default: [2015-02-10T21:30:53+00:00] DEBUG: server: Apache ==> default: [2015-02-10T21:30:53+00:00] DEBUG: location: https://raw.githubusercontent.com/pypa/pip/master/contrib/get-pip.py ==> default: [2015-02-10T21:30:53+00:00] DEBUG: content-length: 0 ==> default: [2015-02-10T21:30:53+00:00] DEBUG: accept-ranges: bytes ==> default: [2015-02-10T21:30:53+00:00] DEBUG: via: 1.1 varnish ==> default: [2015-02-10T21:30:53+00:00] DEBUG: age: 0 ==> default: [2015-02-10T21:30:53+00:00] DEBUG: x-served-by: cache-dfw1824-DFW ==> default: [2015-02-10T21:30:53+00:00] DEBUG: x-cache: MISS ==> default: [2015-02-10T21:30:53+00:00] DEBUG: x-cache-hits: 0 ==> default: [2015-02-10T21:30:53+00:00] DEBUG: vary: Accept-Encoding ==> default: [2015-02-10T21:30:53+00:00] DEBUG: connection: close ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- End HTTP Status/Header Data ---- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- HTTP Response Body ---- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- End HTTP Response Body ----- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_stream_complete ==> default: [2015-02-10T21:30:53+00:00] DEBUG: No content-length information collected for the streamed download, cannot identify streamed download. ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_stream_complete ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_stream_complete ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Following redirect 1/10 ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Initiating GET to https://raw.githubusercontent.com/pypa/pip/master/contrib/get-pip.py ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- HTTP Request Header Data: ---- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- End HTTP Request Header Data ---- ==> default: [2015-02-10T21:30:54+00:00] DEBUG: ---- HTTP Status and Header Data: ---- ==> default: [2015-02-10T21:30:54+00:00] DEBUG: HTTP 1.1 200 OK ==> default: [2015-02-10T21:30:54+00:00] DEBUG: date: Tue, 10 Feb 2015 21:30:55 GMT ==> default: [2015-02-10T21:30:54+00:00] DEBUG: server: Apache ==> default: [2015-02-10T21:30:54+00:00] DEBUG: access-control-allow-origin: https://render.githubusercontent.com ==> default: [2015-02-10T21:30:54+00:00] DEBUG: content-security-policy: default-src 'none' ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-xss-protection: 1; mode=block ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-frame-options: deny ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-content-type-options: nosniff ==> default: [2015-02-10T21:30:54+00:00] DEBUG: strict-transport-security: max-age=31536000 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: etag: "ace0eaab94f9b0d07fe7b6bbdf01b9ff7ff1efbc" ==> default: [2015-02-10T21:30:54+00:00] DEBUG: content-type: text/plain; charset=utf-8 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: cache-control: max-age=300 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: content-encoding: gzip ==> default: [2015-02-10T21:30:54+00:00] DEBUG: content-length: 1288630 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: accept-ranges: bytes ==> default: [2015-02-10T21:30:54+00:00] DEBUG: via: 1.1 varnish ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-served-by: cache-dfw1835-DFW ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-cache: MISS ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-cache-hits: 0 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: vary: Authorization,Accept-Encoding ==> default: [2015-02-10T21:30:54+00:00] DEBUG: expires: Tue, 10 Feb 2015 21:35:55 GMT ==> default: [2015-02-10T21:30:54+00:00] DEBUG: source-age: 0 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: connection: close ==> default: [2015-02-10T21:30:54+00:00] DEBUG: ---- End HTTP Status/Header Data ---- ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Streaming download from https://raw.github.com/pypa/pip/master/contrib/get-pip.py to tempfile /tmp/chef-rest20150210-1632-1tpcbxq ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Initializing gzip stream deflator ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_stream_complete ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Content-Length validated correctly. ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_stream_complete ==> default: [2015-02-10T21:30:55+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_stream_complete ==> default: [2015-02-10T21:30:55+00:00] DEBUG: touching /var/chef/cache/get-pip.py to create it ==> default: [2015-02-10T21:30:55+00:00] INFO: remote_file[/var/chef/cache/get-pip.py] created file /var/chef/cache/get-pip.py ==> default: [2015-02-10T21:30:55+00:00] DEBUG: calculating checksum of /tmp/chef-rest20150210-1632-1tpcbxq to compare with ==> default: [2015-02-10T21:30:56+00:00] DEBUG: running: diff -u /var/chef/cache/get-pip.py /tmp/chef-rest20150210-1632-1tpcbxq ==> default: [2015-02-10T21:30:56+00:00] DEBUG: reading modes from /var/chef/cache/get-pip.py file ==> default: [2015-02-10T21:30:56+00:00] DEBUG: applying mode = 644, uid = 0, gid = 0 to /tmp/chef-rest20150210-1632-1tpcbxq ==> default: [2015-02-10T21:30:56+00:00] DEBUG: moving temporary file /tmp/chef-rest20150210-1632-1tpcbxq into place at /var/chef/cache/get-pip.py ==> default: [2015-02-10T21:30:56+00:00] INFO: remote_file[/var/chef/cache/get-pip.py] updated file contents /var/chef/cache/get-pip.py ==> default: [2015-02-10T21:30:56+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:56+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:56+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-02-10T21:30:56+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-02-10T21:30:56+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-02-10T21:30:56+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-02-10T21:30:56+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:56+00:00] INFO: remote_file[/var/chef/cache/get-pip.py] mode changed to 644 ==> default: [2015-02-10T21:30:56+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-02-10T21:30:56+00:00] INFO: Processing execute[install-setuptools] action run (python::pip line 48) ==> default: [2015-02-10T21:30:56+00:00] INFO: Processing execute[Guard resource] action run (dynamically defined) ==> default: [2015-02-10T21:30:56+00:00] DEBUG: providers for generic execute resource enabled on node include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:56+00:00] DEBUG: providers that refused resource execute[Guard resource] were: [] ==> default: [2015-02-10T21:30:56+00:00] DEBUG: providers that support resource execute[Guard resource] include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:56+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:56+00:00] DEBUG: providers for generic execute resource enabled on node include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:56+00:00] DEBUG: providers that refused resource execute[install-setuptools] were: [] ==> default: [2015-02-10T21:30:56+00:00] DEBUG: providers that support resource execute[install-setuptools] include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:56+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Execute] ==> default: ==> default: ================================================================================ ==> default: Error executing action run on resource 'execute[install-setuptools]' ==> default: ================================================================================ ==> default: ==> default: ==> default: Mixlib::ShellOut::ShellCommandFailed ==> default: ------------------------------------ ==> default: Expected process to exit with [0], but received '127' ==> default: ---- Begin output of /bin/python ez_setup.py ==> default: ---- ==> default: STDOUT: ==> default: STDERR: sh: 1: /bin/python: not found ==> default: ---- End output of /bin/python ez_setup.py ==> default: ---- ==> default: Ran /bin/python ez_setup.py ==> default: returned 127 ==> default: ==> default: ==> default: Resource Declaration: ==> default: --------------------- ==> default: # In /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/recipes/pip.rb ==> default: ==> default: 48: execute "install-setuptools" do ==> default: ==> default: 49: cwd Chef::Config[:file_cache_path] ==> default: 50: command <<-EOF ==> default: 51: #{node['python']['binary']} ez_setup.py ==> default: ==> default: 52: EOF ==> default: 53: not_if "#{node['python']['binary']} -c 'import setuptools'" ==> default: 54: end ==> default: 55: ==> default: ==> default: ==> default: ==> default: Compiled Resource: ==> default: ------------------ ==> default: # Declared in /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/recipes/pip.rb:48:in from_file' ==> default: ==> default: ==> default: execute("install-setuptools") do ==> default: action "run" ==> default: retries 0 ==> default: ==> default: retry_delay 2 ==> default: default_guard_interpreter :execute ==> default: command " /bin/python ez_setup.py\n" ==> default: backup 5 ==> default: cwd "/var/chef/cache" ==> default: ==> default: returns 0 ==> default: ==> default: declared_type :execute ==> default: cookbook_name :python ==> default: recipe_name "pip" ==> default: not_if "/bin/python -c 'import setuptools'" ==> default: end ==> default: ==> default: ==> default: ==> default: [2015-02-10T21:30:56+00:00] INFO: Running queued delayed notifications before re-raising exception ==> default: [2015-02-10T21:30:56+00:00] DEBUG: Re-raising exception: Mixlib::ShellOut::ShellCommandFailed - execute[install-setuptools](python::pip line 48) had an error: Mixlib::ShellOut::ShellCommandFailed: Expected process to exit with [0], but received '127' ==> default: ---- Begin output of /bin/python ez_setup.py ==> default: ---- ==> default: STDOUT: ==> default: STDERR: sh: 1: /bin/python: not found ==> default: ---- End output of /bin/python ez_setup.py ==> default: ---- ==> default: Ran /bin/python ez_setup.py ==> default: returned 127 ==> default: /opt/chef/embedded/lib/ruby/gems/2.1.0/gems/mixlib-shellout-2.0.0/lib/mixlib/shellout.rb:267:ininvalid!' ==> default: /opt/chef/embedded/lib/ruby/gems/2.1.0/gems/mixlib-shellout-2.0.0/lib/mixlib/shellout.rb:254:in error!' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/shell_out.rb:50:inshell_out!' ==> default: /opt/chef/embedded/apps/chef/lib/chef/provider/execute.rb:60:in block in action_run' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/why_run.rb:52:incall' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/why_run.rb:52:in add_action' ==> default: /opt/chef/embedded/apps/chef/lib/chef/provider.rb:180:inconverge_by' ==> default: /opt/chef/embedded/apps/chef/lib/chef/provider/execute.rb:59:in action_run' ==> default: /opt/chef/embedded/apps/chef/lib/chef/provider.rb:145:inrun_action' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource.rb:582:in run_action' ==> default: /opt/chef/embedded/apps/chef/lib/chef/runner.rb:49:inrun_action' ==> default: /opt/chef/embedded/apps/chef/lib/chef/runner.rb:81:in block (2 levels) in converge' ==> default: /opt/chef/embedded/apps/chef/lib/chef/runner.rb:81:ineach' ==> default: /opt/chef/embedded/apps/chef/lib/chef/runner.rb:81:in block in converge' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/resource_list.rb:83:inblock in execute_each_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/stepable_iterator.rb:116:in call' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/stepable_iterator.rb:116:incall_iterator_block' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/stepable_iterator.rb:85:in step' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/stepable_iterator.rb:104:initerate' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/stepable_iterator.rb:55:in each_with_index' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/resource_list.rb:81:inexecute_each_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/runner.rb:80:in converge' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:315:inconverge' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:400:in block in run' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:399:incatch' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:399:in run' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:261:inblock in fork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:in fork' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:infork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:215:in block in run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/local_mode.rb:38:inwith_server_connectivity' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:201:in run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:245:inblock in interval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:in loop' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:ininterval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:224:in run_application' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:58:inrun' ==> default: /opt/chef/embedded/apps/chef/bin/chef-solo:25:in <top (required)>' ==> default: /usr/bin/chef-solo:40:inload' ==> default: /usr/bin/chef-solo:40:in

' ==> default: [2015-02-10T21:30:56+00:00] ERROR: Running exception handlers ==> default: [2015-02-10T21:30:56+00:00] ERROR: Exception handlers complete ==> default: [2015-02-10T21:30:56+00:00] FATAL: Stacktrace dumped to /var/chef/cache/chef-stacktrace.out ==> default: [2015-02-10T21:30:56+00:00] DEBUG: Mixlib::ShellOut::ShellCommandFailed: execute[install-setuptools](python::pip line 48) had an error: Mixlib::ShellOut::ShellCommandFailed: Expected process to exit with [0], but received '127' ==> default: ---- Begin output of /bin/python ez_setup.py ==> default: ---- ==> default: STDOUT: ==> default: STDERR: sh: 1: /bin/python: not found ==> default: ---- End output of /bin/python ez_setup.py ==> default: ---- ==> default: Ran /bin/python ez_setup.py ==> default: returned 127 ==> default: /opt/chef/embedded/lib/ruby/gems/2.1.0/gems/mixlib-shellout-2.0.0/lib/mixlib/shellout.rb:267:ininvalid!' ==> default: /opt/chef/embedded/lib/ruby/gems/2.1.0/gems/mixlib-shellout-2.0.0/lib/mixlib/shellout.rb:254:in error!' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/shell_out.rb:50:inshell_out!' ==> default: /opt/chef/embedded/apps/chef/lib/chef/provider/execute.rb:60:in block in action_run' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/why_run.rb:52:incall' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/why_run.rb:52:in add_action' ==> default: /opt/chef/embedded/apps/chef/lib/chef/provider.rb:180:inconverge_by' ==> default: /opt/chef/embedded/apps/chef/lib/chef/provider/execute.rb:59:in action_run' ==> default: /opt/chef/embedded/apps/chef/lib/chef/provider.rb:145:inrun_action' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource.rb:582:in run_action' ==> default: /opt/chef/embedded/apps/chef/lib/chef/runner.rb:49:inrun_action' ==> default: /opt/chef/embedded/apps/chef/lib/chef/runner.rb:81:in block (2 levels) in converge' ==> default: /opt/chef/embedded/apps/chef/lib/chef/runner.rb:81:ineach' ==> default: /opt/chef/embedded/apps/chef/lib/chef/runner.rb:81:in block in converge' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/resource_list.rb:83:inblock in execute_each_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/stepable_iterator.rb:116:in call' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/stepable_iterator.rb:116:incall_iterator_block' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/stepable_iterator.rb:85:in step' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/stepable_iterator.rb:104:initerate' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/stepable_iterator.rb:55:in each_with_index' ==> default: /opt/chef/embedded/apps/chef/lib/chef/resource_collection/resource_list.rb:81:inexecute_each_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/runner.rb:80:in converge' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:315:inconverge' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:400:in block in run' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:399:incatch' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:399:in run' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:261:inblock in fork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:in fork' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:infork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:215:in block in run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/local_mode.rb:38:inwith_server_connectivity' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:201:in run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:245:inblock in interval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:in loop' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:ininterval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:224:in run_application' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:58:inrun' ==> default: /opt/chef/embedded/apps/chef/bin/chef-solo:25:in <top (required)>' ==> default: /usr/bin/chef-solo:40:inload' ==> default: /usr/bin/chef-solo:40:in `' ==> default: [2015-02-10T21:30:56+00:00] ERROR: executeinstall-setuptools had an error: Mixlib::ShellOut::ShellCommandFailed: Expected process to exit with [0], but received '127' ==> default: ---- Begin output of /bin/python ez_setup.py ==> default: ---- ==> default: STDOUT: ==> default: STDERR: sh: 1: /bin/python: not found ==> default: ---- End output of /bin/python ez_setup.py ==> default: ---- ==> default: Ran /bin/python ez_setup.py ==> default: returned 127 ==> default: [2015-02-10T21:30:56+00:00] FATAL: Chef::Exceptions::ChildConvergeError: Chef run process exited unsuccessfully (exit code 1) Chef never successfully completed! Any errors should be visible in the output above. Please fix your recipes so that they properly complete.

[Cornellio]

Did you create the symlink for Python in the VM as described in the forum?

Pete

On Feb 10, 2015, at 2:44 PM, ezehalac notifications@github.com wrote:

I keep running into this issue... "Chef never successfully completed! " I have read every post here.. tried every combination of vagrant ssh commands, halts, reloads, destroys, downgraded to vagrant 171, etc etc! I have a macbook pro, running Yosemite 10.10.1, Vagrant 1.7.1, Virtualbox 4.3.20.. I followed the instructions on the video STEP by STEP. Please help! I have spent several hours trying to troubleshoot this. See below:

Bringing machine 'default' up with 'virtualbox' provider... ==> default: Importing base box 'precise64'... ==> default: Matching MAC address for NAT networking... ==> default: Setting the name of the VM: Mining-the-Social-Web-2nd-Edition_default_1423603782195_61233 ==> default: Clearing any previously set network interfaces... ==> default: Preparing network interfaces based on configuration... default: Adapter 1: nat ==> default: Forwarding ports... default: 8888 => 8888 (adapter 1) default: 5000 => 5000 (adapter 1) default: 27017 => 27017 (adapter 1) default: 27018 => 27018 (adapter 1) default: 27019 => 27019 (adapter 1) default: 28017 => 28017 (adapter 1) default: 22 => 2222 (adapter 1) ==> default: Running 'pre-boot' VM customizations... ==> default: Booting VM... ==> default: Waiting for machine to boot. This may take a few minutes... default: SSH address: 127.0.0.1:2222 default: SSH username: vagrant default: SSH auth method: private key default: default: Vagrant insecure key detected. Vagrant will automatically replace default: this with a newly generated keypair for better security. default: default: Inserting generated public key within guest... default: Removing insecure key from the guest if its present... default: Key inserted! Disconnecting and reconnecting using new SSH key... ==> default: Machine booted and ready! ==> default: Checking for guest additions in VM... default: The guest additions on this VM do not match the installed version of default: VirtualBox! In most cases this is fine, but in rare cases it can default: prevent things such as shared folders from working properly. If you see default: shared folder errors, please make sure the guest additions within the default: virtual machine match the version of VirtualBox you have installed on default: your host and reload your VM. default: default: Guest Additions Version: 4.2.0 default: VirtualBox Version: 4.3 ==> default: Mounting shared folders... default: /vagrant => /Users/ezehalac/Mining-the-Social-Web-2nd-Edition default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks => /Users/ezehalac/Mining-the-Social-Web-2nd-Edition/deploy/cookbooks ==> default: Running provisioner: chef_solo... default: Installing Chef (latest)... Generating chef JSON and uploading... ==> default: Running chef-solo... ==> default: stdin: is not a tty ==> default: [2015-02-10T21:30:22+00:00] INFO: Forking chef instance to converge... ==> default: [2015-02-10T21:30:22+00:00] DEBUG: Fork successful. Waiting for new chef pid: 1632 ==> default: [2015-02-10T21:30:22+00:00] DEBUG: Forked instance now converging ==> default: [2015-02-10T21:30:22+00:00] INFO: * Chef 12.0.3 * ==> default: [2015-02-10T21:30:22+00:00] INFO: Chef-client pid: 1632 ==> default: [2015-02-10T21:30:22+00:00] DEBUG: Chef-client request_id: 747455c4-1e8b-45b9-84fd-b43bb5a83f78 ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Building node object for precise64 ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Extracting run list from JSON attributes provided on command line ==> default: [2015-02-10T21:30:23+00:00] INFO: Setting the run_list to ["recipe[mtsw2e::default]"] from CLI options ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Applying attributes from json file ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Platform is ubuntu version 12.04 ==> default: [2015-02-10T21:30:23+00:00] INFO: Run List is [recipe[mtsw2e::default]] ==> default: [2015-02-10T21:30:23+00:00] INFO: Run List expands to [mtsw2e::default] ==> default: [2015-02-10T21:30:23+00:00] INFO: Starting Chef Run for precise64 ==> default: [2015-02-10T21:30:23+00:00] INFO: Running start handlers ==> default: [2015-02-10T21:30:23+00:00] INFO: Start handlers complete. ==> default: [2015-02-10T21:30:23+00:00] DEBUG: No chefignore file found at /tmp/vagrant-chef-6/chef-solo-1/chefignore no files will be ignored ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Cookbooks to compile: [:apt, :"build-essential", :yum, :python, :runit, :mtsw2e] ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook runit's library file: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/libraries/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook runit's library file: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/libraries/provider_runit_service.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook runit's library file: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/libraries/resource_runit_service.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook apt's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/attributes/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute apt::default ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook build-essential's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/build-essential/attributes/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute build-essential::default ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/attributes/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute yum::default ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/attributes/elrepo.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute yum::elrepo ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/attributes/epel.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute yum::epel ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/attributes/remi.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute yum::remi ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook python's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/attributes/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute python::default ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Node precise64 loading cookbook runit's attribute file /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/attributes/default.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Attribute runit::default ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook apt's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/providers/preference.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/providers/preference.rb into a provider named apt_preference defined in Chef::Provider::AptPreference ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook apt's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/providers/repository.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/providers/repository.rb into a provider named apt_repository defined in Chef::Provider::AptRepository ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook apt's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/resources/preference.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/resources/preference.rb into a resource named apt_preference defined in Chef::Resource::AptPreference ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook apt's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/resources/repository.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/apt/resources/repository.rb into a resource named apt_repository defined in Chef::Resource::AptRepository ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook yum's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/providers/key.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/providers/key.rb into a provider named yum_key defined in Chef::Provider::YumKey ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook yum's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/providers/repository.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/providers/repository.rb into a provider named yum_repository defined in Chef::Provider::YumRepository ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook yum's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/resources/key.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/resources/key.rb into a resource named yum_key defined in Chef::Resource::YumKey ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook yum's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/resources/repository.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/yum/resources/repository.rb into a resource named yum_repository defined in Chef::Resource::YumRepository ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook python's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/providers/pip.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/providers/pip.rb into a provider named python_pip defined in Chef::Provider::PythonPip ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook python's providers from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/providers/virtualenv.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/providers/virtualenv.rb into a provider named python_virtualenv defined in Chef::Provider::PythonVirtualenv ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook python's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/resources/pip.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/resources/pip.rb into a resource named python_pip defined in Chef::Resource::PythonPip ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading cookbook python's resources from /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/resources/virtualenv.rb ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef-6/chef-solo-1/cookbooks/python/resources/virtualenv.rb into a resource named python_virtualenv defined in Chef::Resource::PythonVirtualenv ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Recipe mtsw2e::default via include_recipe ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Found recipe default in cookbook mtsw2e ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Loading Recipe apt via include_recipe ==> default: [2015-02-10T21:30:23+00:00] DEBUG: Found recipe default in cookbook apt ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe runit via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Found recipe default in cookbook runit ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Caught InvalidVersionConstraint. This means that a key in value_for_platform cannot be interpreted as a Chef::VersionConstraint. ==> default: [2015-02-10T21:30:24+00:00] DEBUG: 'default' (Chef::Exceptions::InvalidVersionConstraint) ==> default: /opt/chef/embedded/apps/chef/lib/chef/version_constraint.rb:113:in parse' ==> default: /opt/chef/embedded/apps/chef/lib/chef/version_constraint.rb:36:ininitialize' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:79:in new' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:79:inblock in match_versions' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:77:in each' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:77:inmatch_versions' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:54:in value_for_node' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:149:invalue_for_platform' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/recipes/default.rb:25:in block in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:120:ininstance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:120:in build_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:82:indeclare_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:42:in method_missing' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/recipes/default.rb:24:infrom_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:in instance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:infrom_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/cookbook_version.rb:245:in load_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:169:inload_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:138:in block in include_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:137:ineach' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:137:in include_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/include_recipe.rb:26:ininclude_recipe' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/mtsw2e/recipes/default.rb:3:in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:ininstance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/cookbook_version.rb:245:inload_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:169:in load_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:140:inblock in compile_recipes' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:138:in each' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:138:incompile_recipes' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:75:in compile' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:92:inload' ==> default: /opt/chef/embedded/apps/chef/lib/chef/policy_builder/expand_node_object.rb:73:in setup_run_context' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:235:insetup_run_context' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:397:in run' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:261:inblock in fork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:in fork' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:infork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:215:in block in run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/local_mode.rb:38:inwith_server_connectivity' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:201:in run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:245:inblock in interval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:in loop' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:ininterval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:224:in run_application' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:58:inrun' ==> default: /opt/chef/embedded/apps/chef/bin/chef-solo:25:in ' ==> default: /usr/bin/chef-solo:40:inload' ==> default: /usr/bin/chef-solo:40:in ' ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Caught InvalidVersionConstraint. This means that a key in value_for_platform cannot be interpreted as a Chef::VersionConstraint. ==> default: [2015-02-10T21:30:24+00:00] DEBUG: 'default' (Chef::Exceptions::InvalidVersionConstraint) ==> default: /opt/chef/embedded/apps/chef/lib/chef/version_constraint.rb:113:inparse' ==> default: /opt/chef/embedded/apps/chef/lib/chef/version_constraint.rb:36:in initialize' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:79:innew' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:79:in block in match_versions' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:77:ineach' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:77:in match_versions' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:54:invalue_for_node' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/platform_introspection.rb:149:in value_for_platform' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/recipes/default.rb:85:inblock in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:120:in instance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:120:inbuild_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:82:in declare_resource' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/recipe.rb:42:inmethod_missing' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/recipes/default.rb:80:in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:ininstance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:in from_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/cookbook_version.rb:245:inload_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:169:in load_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:138:inblock in include_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:137:in each' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:137:ininclude_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/dsl/include_recipe.rb:26:in include_recipe' ==> default: /tmp/vagrant-chef-6/chef-solo-1/cookbooks/mtsw2e/recipes/default.rb:3:infrom_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:in instance_eval' ==> default: /opt/chef/embedded/apps/chef/lib/chef/mixin/from_file.rb:30:infrom_file' ==> default: /opt/chef/embedded/apps/chef/lib/chef/cookbook_version.rb:245:in load_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:169:inload_recipe' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:140:in block in compile_recipes' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:138:ineach' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:138:in compile_recipes' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context/cookbook_compiler.rb:75:incompile' ==> default: /opt/chef/embedded/apps/chef/lib/chef/run_context.rb:92:in load' ==> default: /opt/chef/embedded/apps/chef/lib/chef/policy_builder/expand_node_object.rb:73:insetup_run_context' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:235:in setup_run_context' ==> default: /opt/chef/embedded/apps/chef/lib/chef/client.rb:397:inrun' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:261:in block in fork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:infork' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:249:in fork_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:215:inblock in run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/local_mode.rb:38:in with_server_connectivity' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:201:inrun_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:245:in block in interval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:inloop' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:234:in interval_run_chef_client' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application/solo.rb:224:inrun_application' ==> default: /opt/chef/embedded/apps/chef/lib/chef/application.rb:58:in run' ==> default: /opt/chef/embedded/apps/chef/bin/chef-solo:25:in' ==> default: /usr/bin/chef-solo:40:in load' ==> default: /usr/bin/chef-solo:40:in' ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe python via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Found recipe default in cookbook python ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe python::package via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Found recipe package in cookbook python ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe python::pip via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Found recipe pip in cookbook python ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe python::virtualenv via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Found recipe virtualenv in cookbook python ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading Recipe python::pip via include_recipe ==> default: [2015-02-10T21:30:24+00:00] DEBUG: I am not loading python::pip, because I have already seen it. ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Loading from cookbook_path: /tmp/vagrant-chef-6/chef-solo-1/cookbooks ==> default: [2015-02-10T21:30:24+00:00] DEBUG: Converging node precise64 ==> default: [2015-02-10T21:30:24+00:00] INFO: Processing execute[apt-get-update] action run (apt::default line 22) ==> default: [2015-02-10T21:30:24+00:00] DEBUG: providers for generic execute resource enabled on node include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:24+00:00] DEBUG: providers that refused resource execute[apt-get-update] were: [] ==> default: [2015-02-10T21:30:24+00:00] DEBUG: providers that support resource execute[apt-get-update] include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:24+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:27+00:00] INFO: execute[apt-get-update] ran successfully ==> default: [2015-02-10T21:30:27+00:00] INFO: Processing execute[apt-get update] action nothing (apt::default line 29) ==> default: [2015-02-10T21:30:27+00:00] DEBUG: Skipping execute[apt-get update] due to action :nothing ==> default: [2015-02-10T21:30:27+00:00] INFO: Processing execute[apt-get autoremove] action nothing (apt::default line 36) ==> default: [2015-02-10T21:30:27+00:00] DEBUG: Skipping execute[apt-get autoremove] due to action :nothing ==> default: [2015-02-10T21:30:27+00:00] INFO: Processing execute[apt-get autoclean] action nothing (apt::default line 42) ==> default: [2015-02-10T21:30:27+00:00] DEBUG: Skipping execute[apt-get autoclean] due to action :nothing ==> default: [2015-02-10T21:30:27+00:00] INFO: Processing apt_package[update-notifier-common] action install (apt::default line 48) ==> default: [2015-02-10T21:30:27+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:27+00:00] DEBUG: providers that refused resource apt_package[update-notifier-common] were: [] ==> default: [2015-02-10T21:30:27+00:00] DEBUG: providers that support resource apt_package[update-notifier-common] include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:27+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:27+00:00] DEBUG: apt_package[update-notifier-common] checking package status for update-notifier-common ==> default: [2015-02-10T21:30:27+00:00] DEBUG: apt_package[update-notifier-common] current version is nil ==> default: [2015-02-10T21:30:27+00:00] DEBUG: apt_package[update-notifier-common] candidate version is 0.119ubuntu8.7 ==> default: [2015-02-10T21:30:34+00:00] INFO: apt_package[update-notifier-common] sending run action to executeapt-get-update ==> default: [2015-02-10T21:30:34+00:00] INFO: Processing execute[apt-get-update] action run (apt::default line 22) ==> default: [2015-02-10T21:30:34+00:00] DEBUG: providers for generic execute resource enabled on node include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:34+00:00] DEBUG: providers that refused resource execute[apt-get-update] were: [] ==> default: [2015-02-10T21:30:34+00:00] DEBUG: providers that support resource execute[apt-get-update] include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:34+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Execute] ==> default: [2015-02-10T21:30:37+00:00] INFO: execute[apt-get-update] ran successfully ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing execute[apt-get-update-periodic] action run (apt::default line 52) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Skipping execute[apt-get-update-periodic] due to only_if ruby block ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing directory[/var/cache/local] action create (apt::default line 62) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers for generic directory resource enabled on node include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that refused resource directory[/var/cache/local] were: [] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that support resource directory[/var/cache/local] include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local] created directory /var/cache/local ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local] owner changed to 0 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local] group changed to 0 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local] mode changed to 755 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing directory[/var/cache/local/preseeding] action create (apt::default line 62) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers for generic directory resource enabled on node include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that refused resource directory[/var/cache/local/preseeding] were: [] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that support resource directory[/var/cache/local/preseeding] include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Directory] ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local/preseeding] created directory /var/cache/local/preseeding ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local/preseeding] owner changed to 0 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local/preseeding] group changed to 0 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:37+00:00] INFO: directory[/var/cache/local/preseeding] mode changed to 755 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing service[runit] action nothing (runit::default line 20) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Skipping service[runit] due to action :nothing ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing execute[start-runsvdir] action nothing (runit::default line 24) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Skipping execute[start-runsvdir] due to action :nothing ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing execute[runit-hup-init] action nothing (runit::default line 33) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Skipping execute[runit-hup-init] due to action :nothing ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing apt_package[runit] action install (runit::default line 80) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that refused resource apt_package[runit] were: [] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that support resource apt_package[runit] include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] checking package status for runit ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] current version is nil ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] candidate version is 2.1.1-6.2ubuntu2.1 ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Creating directory /var/chef/cache/preseed ==> default: [2015-02-10T21:30:37+00:00] DEBUG: Creating directory /var/chef/cache/preseed/runit ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] fetching preseed file to /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] fetching preseed file via cookbook_file ==> default: [2015-02-10T21:30:37+00:00] INFO: Processing cookbook_file[/var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed] action create (dynamically defined) ==> default: [2015-02-10T21:30:37+00:00] DEBUG: cookbook_file[/var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed] staging /tmp/vagrant-chef-6/chef-solo-1/cookbooks/runit/files/default/runit.seed to /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150210-1632-tqv74r ==> default: [2015-02-10T21:30:37+00:00] DEBUG: touching /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed to create it ==> default: [2015-02-10T21:30:37+00:00] INFO: cookbook_file[/var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed] created file /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed ==> default: [2015-02-10T21:30:37+00:00] DEBUG: calculating checksum of /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150210-1632-tqv74r to compare with ==> default: [2015-02-10T21:30:37+00:00] DEBUG: running: diff -u /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150210-1632-tqv74r ==> default: [2015-02-10T21:30:37+00:00] DEBUG: reading modes from /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed file ==> default: [2015-02-10T21:30:37+00:00] DEBUG: applying mode = 644, uid = 0, gid = 0 to /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150210-1632-tqv74r ==> default: [2015-02-10T21:30:37+00:00] DEBUG: moving temporary file /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150210-1632-tqv74r into place at /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed ==> default: [2015-02-10T21:30:37+00:00] INFO: cookbook_file[/var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed] updated file contents /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found target_mode == nil, so no mode was specified on resource, not managing mode ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-02-10T21:30:37+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-02-10T21:30:37+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-02-10T21:30:37+00:00] DEBUG: apt_package[runit] fetched preseed file to /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed ==> default: [2015-02-10T21:30:37+00:00] INFO: apt_package[runit] pre-seeding package installation instructions ==> default: [2015-02-10T21:30:40+00:00] INFO: apt_package[runit] sending nothing action to executestart-runsvdir ==> default: [2015-02-10T21:30:40+00:00] INFO: Processing execute[start-runsvdir] action nothing (runit::default line 24) ==> default: [2015-02-10T21:30:40+00:00] DEBUG: Skipping execute[start-runsvdir] due to action :nothing ==> default: [2015-02-10T21:30:40+00:00] INFO: apt_package[runit] sending nothing action to executerunit-hup-init ==> default: [2015-02-10T21:30:40+00:00] INFO: Processing execute[runit-hup-init] action nothing (runit::default line 33) ==> default: [2015-02-10T21:30:40+00:00] DEBUG: Skipping execute[runit-hup-init] due to action :nothing ==> default: [2015-02-10T21:30:40+00:00] INFO: Processing apt_package[python] action install (python::package line 40) ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that refused resource apt_package[python] were: [] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that support resource apt_package[python] include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python] checking package status for python ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python] current version is 2.7.3-0ubuntu2 ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python] candidate version is 2.7.3-0ubuntu2.2 ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python] is already installed - nothing to do ==> default: [2015-02-10T21:30:40+00:00] INFO: Processing apt_package[python-dev] action install (python::package line 40) ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that refused resource apt_package[python-dev] were: [] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that support resource apt_package[python-dev] include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python-dev] checking package status for python-dev ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python-dev] current version is nil ==> default: [2015-02-10T21:30:40+00:00] DEBUG: apt_package[python-dev] candidate version is 2.7.3-0ubuntu2.2 ==> default: [2015-02-10T21:30:52+00:00] INFO: Processing remote_file[/var/chef/cache/ez_setup.py] action create (python::pip line 36) ==> default: [2015-02-10T21:30:52+00:00] DEBUG: remote_file[/var/chef/cache/ez_setup.py] checking for changes ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Cache control headers: {} ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Initiating GET to https://bitbucket.org/pypa/setuptools/raw/0.8/ez_setup.py ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- HTTP Request Header Data: ---- ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- End HTTP Request Header Data ---- ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- HTTP Status and Header Data: ---- ==> default: [2015-02-10T21:30:52+00:00] DEBUG: HTTP 1.1 200 OK ==> default: [2015-02-10T21:30:52+00:00] DEBUG: server: nginx/1.5.10 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: date: Tue, 10 Feb 2015 21:30:53 GMT ==> default: [2015-02-10T21:30:52+00:00] DEBUG: content-type: text/plain ==> default: [2015-02-10T21:30:52+00:00] DEBUG: transfer-encoding: chunked ==> default: [2015-02-10T21:30:52+00:00] DEBUG: connection: close ==> default: [2015-02-10T21:30:52+00:00] DEBUG: vary: Accept-Language, Cookie ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-served-by: app16 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-render-time: 0.0463080406189 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: content-language: en ==> default: [2015-02-10T21:30:52+00:00] DEBUG: cache-control: max-age=0 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-request-count: 70 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: expires: Tue, 10 Feb 2015 21:30:53 GMT ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-frame-options: SAMEORIGIN ==> default: [2015-02-10T21:30:52+00:00] DEBUG: last-modified: Tue, 10 Feb 2015 21:30:53 GMT ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-static-version: dac75071f136 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-version: dac75071f136 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: strict-transport-security: max-age=31536000 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: x-content-type-options: nosniff ==> default: [2015-02-10T21:30:52+00:00] DEBUG: content-encoding: gzip ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- End HTTP Status/Header Data ---- ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Streaming download from https://bitbucket.org/pypa/setuptools/raw/0.8/ez_setup.py to tempfile /tmp/chef-rest20150210-1632-rtulqr ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Initializing gzip stream deflator ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_stream_complete ==> default: [2015-02-10T21:30:52+00:00] DEBUG: HTTP server did not include a Content-Length header in response, cannot identify truncated downloads. ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_stream_complete ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_stream_complete ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Creating directory /var/chef/cache/remote_file ==> default: [2015-02-10T21:30:52+00:00] DEBUG: touching /var/chef/cache/ez_setup.py to create it ==> default: [2015-02-10T21:30:52+00:00] INFO: remote_file[/var/chef/cache/ez_setup.py] created file /var/chef/cache/ez_setup.py ==> default: [2015-02-10T21:30:52+00:00] DEBUG: calculating checksum of /tmp/chef-rest20150210-1632-rtulqr to compare with ==> default: [2015-02-10T21:30:52+00:00] DEBUG: running: diff -u /var/chef/cache/ez_setup.py /tmp/chef-rest20150210-1632-rtulqr ==> default: [2015-02-10T21:30:52+00:00] DEBUG: reading modes from /var/chef/cache/ez_setup.py file ==> default: [2015-02-10T21:30:52+00:00] DEBUG: applying mode = 644, uid = 0, gid = 0 to /tmp/chef-rest20150210-1632-rtulqr ==> default: [2015-02-10T21:30:52+00:00] DEBUG: moving temporary file /tmp/chef-rest20150210-1632-rtulqr into place at /var/chef/cache/ez_setup.py ==> default: [2015-02-10T21:30:52+00:00] INFO: remote_file[/var/chef/cache/ez_setup.py] updated file contents /var/chef/cache/ez_setup.py ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-02-10T21:30:52+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-02-10T21:30:52+00:00] INFO: remote_file[/var/chef/cache/ez_setup.py] mode changed to 644 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-02-10T21:30:52+00:00] INFO: Processing remote_file[/var/chef/cache/get-pip.py] action create (python::pip line 42) ==> default: [2015-02-10T21:30:52+00:00] DEBUG: remote_file[/var/chef/cache/get-pip.py] checking for changes ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Cache control headers: {} ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_request ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Initiating GET to https://raw.github.com/pypa/pip/master/contrib/get-pip.py ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- HTTP Request Header Data: ---- ==> default: [2015-02-10T21:30:52+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 ==> default: [2015-02-10T21:30:52+00:00] DEBUG: ---- End HTTP Request Header Data ---- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- HTTP Status and Header Data: ---- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: HTTP 1.1 301 Moved Permanently ==> default: [2015-02-10T21:30:53+00:00] DEBUG: date: Tue, 10 Feb 2015 21:30:54 GMT ==> default: [2015-02-10T21:30:53+00:00] DEBUG: server: Apache ==> default: [2015-02-10T21:30:53+00:00] DEBUG: location: https://raw.githubusercontent.com/pypa/pip/master/contrib/get-pip.py ==> default: [2015-02-10T21:30:53+00:00] DEBUG: content-length: 0 ==> default: [2015-02-10T21:30:53+00:00] DEBUG: accept-ranges: bytes ==> default: [2015-02-10T21:30:53+00:00] DEBUG: via: 1.1 varnish ==> default: [2015-02-10T21:30:53+00:00] DEBUG: age: 0 ==> default: [2015-02-10T21:30:53+00:00] DEBUG: x-served-by: cache-dfw1824-DFW ==> default: [2015-02-10T21:30:53+00:00] DEBUG: x-cache: MISS ==> default: [2015-02-10T21:30:53+00:00] DEBUG: x-cache-hits: 0 ==> default: [2015-02-10T21:30:53+00:00] DEBUG: vary: Accept-Encoding ==> default: [2015-02-10T21:30:53+00:00] DEBUG: connection: close ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- End HTTP Status/Header Data ---- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- HTTP Response Body ---- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- End HTTP Response Body ----- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_stream_complete ==> default: [2015-02-10T21:30:53+00:00] DEBUG: No content-length information collected for the streamed download, cannot identify streamed download. ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_stream_complete ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_stream_complete ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Following redirect 1/10 ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Initiating GET to https://raw.githubusercontent.com/pypa/pip/master/contrib/get-pip.py ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- HTTP Request Header Data: ---- ==> default: [2015-02-10T21:30:53+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 ==> default: [2015-02-10T21:30:53+00:00] DEBUG: ---- End HTTP Request Header Data ---- ==> default: [2015-02-10T21:30:54+00:00] DEBUG: ---- HTTP Status and Header Data: ---- ==> default: [2015-02-10T21:30:54+00:00] DEBUG: HTTP 1.1 200 OK ==> default: [2015-02-10T21:30:54+00:00] DEBUG: date: Tue, 10 Feb 2015 21:30:55 GMT ==> default: [2015-02-10T21:30:54+00:00] DEBUG: server: Apache ==> default: [2015-02-10T21:30:54+00:00] DEBUG: access-control-allow-origin: https://render.githubusercontent.com ==> default: [2015-02-10T21:30:54+00:00] DEBUG: content-security-policy: default-src 'none' ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-xss-protection: 1; mode=block ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-frame-options: deny ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-content-type-options: nosniff ==> default: [2015-02-10T21:30:54+00:00] DEBUG: strict-transport-security: max-age=31536000 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: etag: "ace0eaab94f9b0d07fe7b6bbdf01b9ff7ff1efbc" ==> default: [2015-02-10T21:30:54+00:00] DEBUG: content-type: text/plain; charset=utf-8 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: cache-control: max-age=300 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: content-encoding: gzip ==> default: [2015-02-10T21:30:54+00:00] DEBUG: content-length: 1288630 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: accept-ranges: bytes ==> default: [2015-02-10T21:30:54+00:00] DEBUG: via: 1.1 varnish ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-served-by: cache-dfw1835-DFW ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-cache: MISS ==> default: [2015-02-10T21:30:54+00:00] DEBUG: x-cache-hits: 0 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: vary: Authorization,Accept-Encoding ==> default: [2015-02-10T21:30:54+00:00] DEBUG: expires: Tue, 10 Feb 2015 21:35:55 GMT ==> default: [2015-02-10T21:30:54+00:00] DEBUG: source-age: 0 ==> default: [2015-02-10T21:30:54+00:00] DEBUG: connection: close ==> default: [2015-02-10T21:30:54+00:00] DEBUG: ---- End HTTP Status/Header Data ---- ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Streaming download from https://raw.github.com/pypa/pip/master/contrib/get-pip.py to tempfile /tmp/chef-rest20150210-1632-1tpcbxq ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Initializing gzip stream deflator ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-02-10T21:30:54+00:00] DEBUG: Che

[ezehalac]

ok started all from scratch and it worked!