Vagrant Up didn't success

I followed the Screencast Overview, and the last step 'Vagrant Up' failed. Chef never successfully completed! with the ERROR ==> default: Error executing action run on resource 'execute[install-setuptools]'. The I tried localhost:8888, it didn't work.

I am quite new to Vagrant and all and have no idea what the Error is about. Could you give me some advice?

dyn-160-39-6-140:Mining-the-Social-Web-2nd-Edition fanheng$ vagrant up 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_1422475862946_75810 ==> 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: Warning: Connection timeout. Retrying... 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/fanheng/Mining-the-Social-Web-2nd-Edition default: /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks => /Users/fanheng/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-01-28T20:14:14+00:00] INFO: Forking chef instance to converge... ==> default: [2015-01-28T20:14:14+00:00] DEBUG: Fork successful. Waiting for new chef pid: 1596 ==> default: [2015-01-28T20:14:14+00:00] DEBUG: Forked instance now converging ==> default: [2015-01-28T20:14:14+00:00] INFO: * Chef 12.0.3 * ==> default: [2015-01-28T20:14:14+00:00] INFO: Chef-client pid: 1596 ==> default: [2015-01-28T20:14:14+00:00] DEBUG: Chef-client request_id: d550c824-f092-4838-9cf4-dad12f8d626f ==> default: [2015-01-28T20:14:16+00:00] DEBUG: Building node object for precise64 ==> default: [2015-01-28T20:14:16+00:00] DEBUG: Extracting run list from JSON attributes provided on command line ==> default: [2015-01-28T20:14:16+00:00] INFO: Setting the run_list to ["recipe[mtsw2e::default]"] from CLI options ==> default: [2015-01-28T20:14:16+00:00] DEBUG: Applying attributes from json file ==> default: [2015-01-28T20:14:16+00:00] DEBUG: Platform is ubuntu version 12.04 ==> default: [2015-01-28T20:14:16+00:00] INFO: Run List is [recipe[mtsw2e::default]] ==> default: [2015-01-28T20:14:16+00:00] INFO: Run List expands to [mtsw2e::default] ==> default: [2015-01-28T20:14:16+00:00] INFO: Starting Chef Run for precise64 ==> default: [2015-01-28T20:14:16+00:00] INFO: Running start handlers ==> default: [2015-01-28T20:14:16+00:00] INFO: Start handlers complete. ==> default: [2015-01-28T20:14:16+00:00] DEBUG: No chefignore file found at /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/chefignore no files will be ignored ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Cookbooks to compile: [:apt, :"build-essential", :yum, :python, :runit, :mtsw2e] ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook runit's library file: /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/runit/libraries/default.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook runit's library file: /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/runit/libraries/provider_runit_service.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook runit's library file: /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/runit/libraries/resource_runit_service.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Node precise64 loading cookbook apt's attribute file /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/apt/attributes/default.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Attribute apt::default ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Node precise64 loading cookbook build-essential's attribute file /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/build-essential/attributes/default.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Attribute build-essential::default ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/attributes/default.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Attribute yum::default ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/attributes/elrepo.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Attribute yum::elrepo ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/attributes/epel.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Attribute yum::epel ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Node precise64 loading cookbook yum's attribute file /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/attributes/remi.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Attribute yum::remi ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Node precise64 loading cookbook python's attribute file /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/python/attributes/default.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Attribute python::default ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Node precise64 loading cookbook runit's attribute file /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/runit/attributes/default.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Attribute runit::default ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook apt's providers from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/apt/providers/preference.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/apt/providers/preference.rb into a provider named apt_preference defined in Chef::Provider::AptPreference ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook apt's providers from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/apt/providers/repository.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/apt/providers/repository.rb into a provider named apt_repository defined in Chef::Provider::AptRepository ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook apt's resources from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/apt/resources/preference.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/apt/resources/preference.rb into a resource named apt_preference defined in Chef::Resource::AptPreference ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook apt's resources from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/apt/resources/repository.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/apt/resources/repository.rb into a resource named apt_repository defined in Chef::Resource::AptRepository ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook yum's providers from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/providers/key.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/providers/key.rb into a provider named yum_key defined in Chef::Provider::YumKey ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook yum's providers from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/providers/repository.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/providers/repository.rb into a provider named yum_repository defined in Chef::Provider::YumRepository ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook yum's resources from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/resources/key.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/resources/key.rb into a resource named yum_key defined in Chef::Resource::YumKey ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook yum's resources from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/resources/repository.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/yum/resources/repository.rb into a resource named yum_repository defined in Chef::Resource::YumRepository ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook python's providers from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/python/providers/pip.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/python/providers/pip.rb into a provider named python_pip defined in Chef::Provider::PythonPip ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook python's providers from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/python/providers/virtualenv.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/python/providers/virtualenv.rb into a provider named python_virtualenv defined in Chef::Provider::PythonVirtualenv ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook python's resources from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/python/resources/pip.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/python/resources/pip.rb into a resource named python_pip defined in Chef::Resource::PythonPip ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading cookbook python's resources from /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/python/resources/virtualenv.rb ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loaded contents of /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/python/resources/virtualenv.rb into a resource named python_virtualenv defined in Chef::Resource::PythonVirtualenv ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Recipe mtsw2e::default via include_recipe ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Found recipe default in cookbook mtsw2e ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Recipe apt via include_recipe ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Found recipe default in cookbook apt ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Recipe runit via include_recipe ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Found recipe default in cookbook runit ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Caught InvalidVersionConstraint. This means that a key in value_for_platform cannot be interpreted as a Chef::VersionConstraint. ==> default: [2015-01-28T20:14:17+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/717c26c229ad1267bad0e2d72dcf9a57/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/717c26c229ad1267bad0e2d72dcf9a57/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/717c26c229ad1267bad0e2d72dcf9a57/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 <main>' ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Caught InvalidVersionConstraint. This means that a key in value_for_platform cannot be interpreted as a Chef::VersionConstraint. ==> default: [2015-01-28T20:14:17+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/717c26c229ad1267bad0e2d72dcf9a57/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/717c26c229ad1267bad0e2d72dcf9a57/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/717c26c229ad1267bad0e2d72dcf9a57/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<top (required)>' ==> default: /usr/bin/chef-solo:40:in load' ==> default: /usr/bin/chef-solo:40:in

' ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Recipe python via include_recipe ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Found recipe default in cookbook python ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Recipe python::package via include_recipe ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Found recipe package in cookbook python ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Recipe python::pip via include_recipe ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Found recipe pip in cookbook python ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Recipe python::virtualenv via include_recipe ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Found recipe virtualenv in cookbook python ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading Recipe python::pip via include_recipe ==> default: [2015-01-28T20:14:17+00:00] DEBUG: I am not loading python::pip, because I have already seen it. ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Loading from cookbook_path: /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks ==> default: [2015-01-28T20:14:17+00:00] DEBUG: Converging node precise64 ==> default: [2015-01-28T20:14:17+00:00] INFO: Processing execute[apt-get-update] action run (apt::default line 22) ==> default: [2015-01-28T20:14:17+00:00] DEBUG: providers for generic execute resource enabled on node include: [Chef::Provider::Execute] ==> default: [2015-01-28T20:14:17+00:00] DEBUG: providers that refused resource execute[apt-get-update] were: [] ==> default: [2015-01-28T20:14:17+00:00] DEBUG: providers that support resource execute[apt-get-update] include: [Chef::Provider::Execute] ==> default: [2015-01-28T20:14:17+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Execute] ==> default: [2015-01-28T20:14:24+00:00] INFO: execute[apt-get-update] ran successfully ==> default: [2015-01-28T20:14:24+00:00] INFO: Processing execute[apt-get update] action nothing (apt::default line 29) ==> default: [2015-01-28T20:14:24+00:00] DEBUG: Skipping execute[apt-get update] due to action :nothing ==> default: [2015-01-28T20:14:24+00:00] INFO: Processing execute[apt-get autoremove] action nothing (apt::default line 36) ==> default: [2015-01-28T20:14:24+00:00] DEBUG: Skipping execute[apt-get autoremove] due to action :nothing ==> default: [2015-01-28T20:14:24+00:00] INFO: Processing execute[apt-get autoclean] action nothing (apt::default line 42) ==> default: [2015-01-28T20:14:24+00:00] DEBUG: Skipping execute[apt-get autoclean] due to action :nothing ==> default: [2015-01-28T20:14:24+00:00] INFO: Processing apt_package[update-notifier-common] action install (apt::default line 48) ==> default: [2015-01-28T20:14:24+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:24+00:00] DEBUG: providers that refused resource apt_package[update-notifier-common] were: [] ==> default: [2015-01-28T20:14:24+00:00] DEBUG: providers that support resource apt_package[update-notifier-common] include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:24+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:24+00:00] DEBUG: apt_package[update-notifier-common] checking package status for update-notifier-common ==> default: [2015-01-28T20:14:24+00:00] DEBUG: apt_package[update-notifier-common] current version is nil ==> default: [2015-01-28T20:14:24+00:00] DEBUG: apt_package[update-notifier-common] candidate version is 0.119ubuntu8.7 ==> default: [2015-01-28T20:14:29+00:00] INFO: apt_package[update-notifier-common] sending run action to executeapt-get-update ==> default: [2015-01-28T20:14:29+00:00] INFO: Processing execute[apt-get-update] action run (apt::default line 22) ==> default: [2015-01-28T20:14:29+00:00] DEBUG: providers for generic execute resource enabled on node include: [Chef::Provider::Execute] ==> default: [2015-01-28T20:14:29+00:00] DEBUG: providers that refused resource execute[apt-get-update] were: [] ==> default: [2015-01-28T20:14:29+00:00] DEBUG: providers that support resource execute[apt-get-update] include: [Chef::Provider::Execute] ==> default: [2015-01-28T20:14:29+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Execute] ==> default: [2015-01-28T20:14:36+00:00] INFO: execute[apt-get-update] ran successfully ==> default: [2015-01-28T20:14:36+00:00] INFO: Processing execute[apt-get-update-periodic] action run (apt::default line 52) ==> default: [2015-01-28T20:14:36+00:00] DEBUG: Skipping execute[apt-get-update-periodic] due to only_if ruby block ==> default: [2015-01-28T20:14:36+00:00] INFO: Processing directory[/var/cache/local] action create (apt::default line 62) ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers for generic directory resource enabled on node include: [Chef::Provider::Directory] ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers that refused resource directory[/var/cache/local] were: [] ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers that support resource directory[/var/cache/local] include: [Chef::Provider::Directory] ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Directory] ==> default: [2015-01-28T20:14:36+00:00] INFO: directory[/var/cache/local] created directory /var/cache/local ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-01-28T20:14:36+00:00] INFO: directory[/var/cache/local] owner changed to 0 ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-01-28T20:14:36+00:00] INFO: directory[/var/cache/local] group changed to 0 ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:14:36+00:00] INFO: directory[/var/cache/local] mode changed to 755 ==> default: [2015-01-28T20:14:36+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-01-28T20:14:36+00:00] INFO: Processing directory[/var/cache/local/preseeding] action create (apt::default line 62) ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers for generic directory resource enabled on node include: [Chef::Provider::Directory] ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers that refused resource directory[/var/cache/local/preseeding] were: [] ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers that support resource directory[/var/cache/local/preseeding] include: [Chef::Provider::Directory] ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Directory] ==> default: [2015-01-28T20:14:36+00:00] INFO: directory[/var/cache/local/preseeding] created directory /var/cache/local/preseeding ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_uid == nil, so we are creating a new file, updating owner ==> default: [2015-01-28T20:14:36+00:00] INFO: directory[/var/cache/local/preseeding] owner changed to 0 ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_gid == nil, so we are creating a new file, updating group ==> default: [2015-01-28T20:14:36+00:00] INFO: directory[/var/cache/local/preseeding] group changed to 0 ==> default: [2015-01-28T20:14:36+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:14:36+00:00] INFO: directory[/var/cache/local/preseeding] mode changed to 755 ==> default: [2015-01-28T20:14:36+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-01-28T20:14:36+00:00] INFO: Processing service[runit] action nothing (runit::default line 20) ==> default: [2015-01-28T20:14:36+00:00] DEBUG: Skipping service[runit] due to action :nothing ==> default: [2015-01-28T20:14:36+00:00] INFO: Processing execute[start-runsvdir] action nothing (runit::default line 24) ==> default: [2015-01-28T20:14:36+00:00] DEBUG: Skipping execute[start-runsvdir] due to action :nothing ==> default: [2015-01-28T20:14:36+00:00] INFO: Processing execute[runit-hup-init] action nothing (runit::default line 33) ==> default: [2015-01-28T20:14:36+00:00] DEBUG: Skipping execute[runit-hup-init] due to action :nothing ==> default: [2015-01-28T20:14:36+00:00] INFO: Processing apt_package[runit] action install (runit::default line 80) ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers that refused resource apt_package[runit] were: [] ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers that support resource apt_package[runit] include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:36+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:36+00:00] DEBUG: apt_package[runit] checking package status for runit ==> default: [2015-01-28T20:14:36+00:00] DEBUG: apt_package[runit] current version is nil ==> default: [2015-01-28T20:14:36+00:00] DEBUG: apt_package[runit] candidate version is 2.1.1-6.2ubuntu2.1 ==> default: [2015-01-28T20:14:36+00:00] DEBUG: Creating directory /var/chef/cache/preseed ==> default: [2015-01-28T20:14:36+00:00] DEBUG: Creating directory /var/chef/cache/preseed/runit ==> default: [2015-01-28T20:14:36+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-01-28T20:14:37+00:00] DEBUG: apt_package[runit] fetching preseed file via cookbook_file ==> default: [2015-01-28T20:14: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-01-28T20:14:37+00:00] DEBUG: cookbook_file[/var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed] staging /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/runit/files/default/runit.seed to /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150128-1596-19iu7bq ==> default: [2015-01-28T20:14:37+00:00] DEBUG: touching /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed to create it ==> default: [2015-01-28T20:14: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-01-28T20:14:37+00:00] DEBUG: calculating checksum of /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150128-1596-19iu7bq to compare with ==> default: [2015-01-28T20:14: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.seed20150128-1596-19iu7bq ==> default: [2015-01-28T20:14:37+00:00] DEBUG: reading modes from /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed file ==> default: [2015-01-28T20:14:37+00:00] DEBUG: applying mode = 644, uid = 0, gid = 0 to /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150128-1596-19iu7bq ==> default: [2015-01-28T20:14:37+00:00] DEBUG: moving temporary file /var/chef/cache/preseed/runit/.runit-2.1.1-6.2ubuntu2.1.seed20150128-1596-19iu7bq into place at /var/chef/cache/preseed/runit/runit-2.1.1-6.2ubuntu2.1.seed ==> default: [2015-01-28T20:14: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-01-28T20:14:37+00:00] DEBUG: found target_mode == nil, so no mode was specified on resource, not managing mode ==> default: [2015-01-28T20:14:37+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-01-28T20:14:37+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-01-28T20:14:37+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-01-28T20:14: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-01-28T20:14:37+00:00] INFO: apt_package[runit] pre-seeding package installation instructions ==> default: [2015-01-28T20:14:41+00:00] INFO: apt_package[runit] sending nothing action to executestart-runsvdir ==> default: [2015-01-28T20:14:41+00:00] INFO: Processing execute[start-runsvdir] action nothing (runit::default line 24) ==> default: [2015-01-28T20:14:41+00:00] DEBUG: Skipping execute[start-runsvdir] due to action :nothing ==> default: [2015-01-28T20:14:41+00:00] INFO: apt_package[runit] sending nothing action to executerunit-hup-init ==> default: [2015-01-28T20:14:41+00:00] INFO: Processing execute[runit-hup-init] action nothing (runit::default line 33) ==> default: [2015-01-28T20:14:41+00:00] DEBUG: Skipping execute[runit-hup-init] due to action :nothing ==> default: [2015-01-28T20:14:41+00:00] INFO: Processing apt_package[python] action install (python::package line 40) ==> default: [2015-01-28T20:14:41+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:41+00:00] DEBUG: providers that refused resource apt_package[python] were: [] ==> default: [2015-01-28T20:14:41+00:00] DEBUG: providers that support resource apt_package[python] include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:41+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:41+00:00] DEBUG: apt_package[python] checking package status for python ==> default: [2015-01-28T20:14:41+00:00] DEBUG: apt_package[python] current version is 2.7.3-0ubuntu2 ==> default: [2015-01-28T20:14:41+00:00] DEBUG: apt_package[python] candidate version is 2.7.3-0ubuntu2.2 ==> default: [2015-01-28T20:14:41+00:00] DEBUG: apt_package[python] is already installed - nothing to do ==> default: [2015-01-28T20:14:41+00:00] INFO: Processing apt_package[python-dev] action install (python::package line 40) ==> default: [2015-01-28T20:14:41+00:00] DEBUG: providers for generic apt_package resource enabled on node include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:41+00:00] DEBUG: providers that refused resource apt_package[python-dev] were: [] ==> default: [2015-01-28T20:14:41+00:00] DEBUG: providers that support resource apt_package[python-dev] include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:41+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Package::Apt] ==> default: [2015-01-28T20:14:41+00:00] DEBUG: apt_package[python-dev] checking package status for python-dev ==> default: [2015-01-28T20:14:41+00:00] DEBUG: apt_package[python-dev] current version is nil ==> default: [2015-01-28T20:14:41+00:00] DEBUG: apt_package[python-dev] candidate version is 2.7.3-0ubuntu2.2 ==> default: [2015-01-28T20:14:58+00:00] INFO: Processing remote_file[/var/chef/cache/ez_setup.py] action create (python::pip line 36) ==> default: [2015-01-28T20:14:58+00:00] DEBUG: remote_file[/var/chef/cache/ez_setup.py] checking for changes ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Cache control headers: {} ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_request ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_request ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_request ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Initiating GET to https://bitbucket.org/pypa/setuptools/raw/0.8/ez_setup.py ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- HTTP Request Header Data: ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- End HTTP Request Header Data ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- HTTP Status and Header Data: ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: HTTP 1.1 200 OK ==> default: [2015-01-28T20:14:58+00:00] DEBUG: server: nginx/1.5.10 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: date: Wed, 28 Jan 2015 20:14:58 GMT ==> default: [2015-01-28T20:14:58+00:00] DEBUG: content-type: text/plain ==> default: [2015-01-28T20:14:58+00:00] DEBUG: transfer-encoding: chunked ==> default: [2015-01-28T20:14:58+00:00] DEBUG: connection: close ==> default: [2015-01-28T20:14:58+00:00] DEBUG: vary: Accept-Language, Cookie ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-served-by: app15 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-render-time: 0.0519771575928 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: content-language: en ==> default: [2015-01-28T20:14:58+00:00] DEBUG: cache-control: max-age=0 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-request-count: 106 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: expires: Wed, 28 Jan 2015 20:14:58 GMT ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-frame-options: SAMEORIGIN ==> default: [2015-01-28T20:14:58+00:00] DEBUG: last-modified: Wed, 28 Jan 2015 20:14:58 GMT ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-static-version: dfe627631238 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-version: 918c87cf75a9 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: strict-transport-security: max-age=31536000 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-content-type-options: nosniff ==> default: [2015-01-28T20:14:58+00:00] DEBUG: content-encoding: gzip ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- End HTTP Status/Header Data ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Streaming download from https://bitbucket.org/pypa/setuptools/raw/0.8/ez_setup.py to tempfile /tmp/chef-rest20150128-1596-j5aaqw ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Initializing gzip stream deflator ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_stream_complete ==> default: [2015-01-28T20:14:58+00:00] DEBUG: HTTP server did not include a Content-Length header in response, cannot identify truncated downloads. ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_stream_complete ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_stream_complete ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Creating directory /var/chef/cache/remote_file ==> default: [2015-01-28T20:14:58+00:00] DEBUG: touching /var/chef/cache/ez_setup.py to create it ==> default: [2015-01-28T20:14:58+00:00] INFO: remote_file[/var/chef/cache/ez_setup.py] created file /var/chef/cache/ez_setup.py ==> default: [2015-01-28T20:14:58+00:00] DEBUG: calculating checksum of /tmp/chef-rest20150128-1596-j5aaqw to compare with ==> default: [2015-01-28T20:14:58+00:00] DEBUG: running: diff -u /var/chef/cache/ez_setup.py /tmp/chef-rest20150128-1596-j5aaqw ==> default: [2015-01-28T20:14:58+00:00] DEBUG: reading modes from /var/chef/cache/ez_setup.py file ==> default: [2015-01-28T20:14:58+00:00] DEBUG: applying mode = 644, uid = 0, gid = 0 to /tmp/chef-rest20150128-1596-j5aaqw ==> default: [2015-01-28T20:14:58+00:00] DEBUG: moving temporary file /tmp/chef-rest20150128-1596-j5aaqw into place at /var/chef/cache/ez_setup.py ==> default: [2015-01-28T20:14:58+00:00] INFO: remote_file[/var/chef/cache/ez_setup.py] updated file contents /var/chef/cache/ez_setup.py ==> default: [2015-01-28T20:14:58+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:14:58+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:14:58+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-01-28T20:14:58+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-01-28T20:14:58+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-01-28T20:14:58+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-01-28T20:14:58+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:14:58+00:00] INFO: remote_file[/var/chef/cache/ez_setup.py] mode changed to 644 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-01-28T20:14:58+00:00] INFO: Processing remote_file[/var/chef/cache/get-pip.py] action create (python::pip line 42) ==> default: [2015-01-28T20:14:58+00:00] DEBUG: remote_file[/var/chef/cache/get-pip.py] checking for changes ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Cache control headers: {} ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_request ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_request ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_request ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Initiating GET to https://raw.github.com/pypa/pip/master/contrib/get-pip.py ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- HTTP Request Header Data: ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- End HTTP Request Header Data ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- HTTP Status and Header Data: ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: HTTP 1.1 301 Moved Permanently ==> default: [2015-01-28T20:14:58+00:00] DEBUG: date: Wed, 28 Jan 2015 20:14:58 GMT ==> default: [2015-01-28T20:14:58+00:00] DEBUG: server: Apache ==> default: [2015-01-28T20:14:58+00:00] DEBUG: location: https://raw.githubusercontent.com/pypa/pip/master/contrib/get-pip.py ==> default: [2015-01-28T20:14:58+00:00] DEBUG: content-length: 0 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: accept-ranges: bytes ==> default: [2015-01-28T20:14:58+00:00] DEBUG: via: 1.1 varnish ==> default: [2015-01-28T20:14:58+00:00] DEBUG: age: 0 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-served-by: cache-jfk1031-JFK ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-cache: MISS ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-cache-hits: 0 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: vary: Accept-Encoding ==> default: [2015-01-28T20:14:58+00:00] DEBUG: connection: close ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- End HTTP Status/Header Data ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- HTTP Response Body ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- End HTTP Response Body ----- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_stream_complete ==> default: [2015-01-28T20:14:58+00:00] DEBUG: No content-length information collected for the streamed download, cannot identify streamed download. ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_stream_complete ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_stream_complete ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Following redirect 1/10 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Initiating GET to https://raw.githubusercontent.com/pypa/pip/master/contrib/get-pip.py ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- HTTP Request Header Data: ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Accept-Encoding: gzip;q=1.0,deflate;q=0.6,identity;q=0.3 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- End HTTP Request Header Data ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- HTTP Status and Header Data: ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: HTTP 1.1 200 OK ==> default: [2015-01-28T20:14:58+00:00] DEBUG: date: Wed, 28 Jan 2015 20:14:58 GMT ==> default: [2015-01-28T20:14:58+00:00] DEBUG: server: Apache ==> default: [2015-01-28T20:14:58+00:00] DEBUG: access-control-allow-origin: https://render.githubusercontent.com ==> default: [2015-01-28T20:14:58+00:00] DEBUG: content-security-policy: default-src 'none' ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-xss-protection: 1; mode=block ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-frame-options: deny ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-content-type-options: nosniff ==> default: [2015-01-28T20:14:58+00:00] DEBUG: strict-transport-security: max-age=31536000 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: etag: "9b45a7737a204f3255bc32d7bc3a125cc08c80dc" ==> default: [2015-01-28T20:14:58+00:00] DEBUG: content-type: text/plain; charset=utf-8 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: cache-control: max-age=300 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: content-encoding: gzip ==> default: [2015-01-28T20:14:58+00:00] DEBUG: content-length: 1268874 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: accept-ranges: bytes ==> default: [2015-01-28T20:14:58+00:00] DEBUG: via: 1.1 varnish ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-served-by: cache-jfk1034-JFK ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-cache: HIT ==> default: [2015-01-28T20:14:58+00:00] DEBUG: x-cache-hits: 1 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: vary: Authorization,Accept-Encoding ==> default: [2015-01-28T20:14:58+00:00] DEBUG: expires: Wed, 28 Jan 2015 20:19:58 GMT ==> default: [2015-01-28T20:14:58+00:00] DEBUG: source-age: 253 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: connection: close ==> default: [2015-01-28T20:14:58+00:00] DEBUG: ---- End HTTP Status/Header Data ---- ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Streaming download from https://raw.github.com/pypa/pip/master/contrib/get-pip.py to tempfile /tmp/chef-rest20150128-1596-1pqhvc8 ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Initializing gzip stream deflator ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:14:58+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:00+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:01+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::ValidateContentLength::ContentLengthCounter#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP::StreamHandler calling Chef::HTTP::Decompressor::GzipInflater#handle_chunk ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::ValidateContentLength#handle_stream_complete ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Content-Length validated correctly. ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::CookieManager#handle_stream_complete ==> default: [2015-01-28T20:15:03+00:00] DEBUG: Chef::HTTP calling Chef::HTTP::Decompressor#handle_stream_complete ==> default: [2015-01-28T20:15:03+00:00] DEBUG: touching /var/chef/cache/get-pip.py to create it ==> default: [2015-01-28T20:15:03+00:00] INFO: remote_file[/var/chef/cache/get-pip.py] created file /var/chef/cache/get-pip.py ==> default: [2015-01-28T20:15:03+00:00] DEBUG: calculating checksum of /tmp/chef-rest20150128-1596-1pqhvc8 to compare with ==> default: [2015-01-28T20:15:04+00:00] DEBUG: running: diff -u /var/chef/cache/get-pip.py /tmp/chef-rest20150128-1596-1pqhvc8 ==> default: [2015-01-28T20:15:04+00:00] DEBUG: reading modes from /var/chef/cache/get-pip.py file ==> default: [2015-01-28T20:15:04+00:00] DEBUG: applying mode = 644, uid = 0, gid = 0 to /tmp/chef-rest20150128-1596-1pqhvc8 ==> default: [2015-01-28T20:15:04+00:00] DEBUG: moving temporary file /tmp/chef-rest20150128-1596-1pqhvc8 into place at /var/chef/cache/get-pip.py ==> default: [2015-01-28T20:15:04+00:00] INFO: remote_file[/var/chef/cache/get-pip.py] updated file contents /var/chef/cache/get-pip.py ==> default: [2015-01-28T20:15:04+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:15:04+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:15:04+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-01-28T20:15:04+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-01-28T20:15:04+00:00] DEBUG: found target_uid == nil, so no owner was specified on resource, not managing owner ==> default: [2015-01-28T20:15:04+00:00] DEBUG: found target_gid == nil, so no group was specified on resource, not managing group ==> default: [2015-01-28T20:15:04+00:00] DEBUG: found current_mode == nil, so we are creating a new file, updating mode ==> default: [2015-01-28T20:15:04+00:00] INFO: remote_file[/var/chef/cache/get-pip.py] mode changed to 644 ==> default: [2015-01-28T20:15:04+00:00] DEBUG: selinux utilities can not be found. Skipping selinux permission fixup. ==> default: [2015-01-28T20:15:04+00:00] INFO: Processing execute[install-setuptools] action run (python::pip line 48) ==> default: [2015-01-28T20:15:04+00:00] INFO: Processing execute[Guard resource] action run (dynamically defined) ==> default: [2015-01-28T20:15:04+00:00] DEBUG: providers for generic execute resource enabled on node include: [Chef::Provider::Execute] ==> default: [2015-01-28T20:15:04+00:00] DEBUG: providers that refused resource execute[Guard resource] were: [] ==> default: [2015-01-28T20:15:04+00:00] DEBUG: providers that support resource execute[Guard resource] include: [Chef::Provider::Execute] ==> default: [2015-01-28T20:15:04+00:00] DEBUG: providers that survived replacement include: [Chef::Provider::Execute] ==> default: [2015-01-28T20:15:04+00:00] DEBUG: providers for generic execute resource enabled on node include: [Chef::Provider::Execute] ==> default: [2015-01-28T20:15:04+00:00] DEBUG: providers that refused resource execute[install-setuptools] were: [] ==> default: [2015-01-28T20:15:04+00:00] DEBUG: providers that support resource execute[install-setuptools] include: [Chef::Provider::Execute] ==> default: [2015-01-28T20:15:04+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: 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: ==> default: ---- End output of /bin/python ez_setup.py ==> default: ---- ==> default: ==> default: Ran /bin/python ez_setup.py ==> default: returned 127 ==> default: ==> default: Resource Declaration: ==> default: ==> default: --------------------- ==> default: # In /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/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: 52: EOF ==> default: 53: not_if "#{node['python']['binary']} -c 'import setuptools'" ==> default: ==> default: 54: end ==> default: 55: ==> default: ==> default: ==> default: Compiled Resource: ==> default: ------------------ ==> default: # Declared in /tmp/vagrant-chef/717c26c229ad1267bad0e2d72dcf9a57/cookbooks/python/recipes/pip.rb:48:in

from_file' ==> default: ==> default: execute("install-setuptools") do ==> default:   action "run" ==> default: ==> default:   retries 0 ==> 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:   returns 0 ==> default:   declared_type :execute ==> default:   cookbook_name :python ==> default:   recipe_name "pip" ==> default:   not_if "/bin/python -c 'import setuptools'" ==> default: end ==> default: ==> default: [2015-01-28T20:15:04+00:00] INFO: Running queued delayed notifications before re-raising exception ==> default: [2015-01-28T20:15:04+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:in

invalid!' ==> 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

<main>' ==> default: [2015-01-28T20:15:04+00:00] ERROR: Running exception handlers ==> default: [2015-01-28T20:15:04+00:00] ERROR: Exception handlers complete ==> default: [2015-01-28T20:15:04+00:00] FATAL: Stacktrace dumped to /var/chef/cache/chef-stacktrace.out ==> default: [2015-01-28T20:15:04+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:in

' ==> default: [2015-01-28T20:15:04+00:00] ERROR: 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: [2015-01-28T20:15:04+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.

ptwobrussell / Mining-the-Social-Web-2nd-Edition

Vagrant Up didn't success #256