Attachment 915793 Details for Bug 1023520 – Comment

Comment

comment_6458989.txt (text/plain), 68.18 KB, created by Dan McPherson on 2013-10-25 19:48:52 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Dan McPherson

Created: 2013-10-25 19:48:52 UTC

Size: 68.18 KB

patch

obsolete

>platform.log from 1)
>
>
>[dmcphers@ex-std-node2.int node]$ grep -A10 -B10 '526a5a9903ef64b8e0000003' platform.log 
>October 25 07:47:37 INFO Shell command 'setquota --always-resolve -u 526a5a0403ef64b8a70001b5 0 0 0 0 -a /var/lib/openshift' ran. rc=1 out=
>October 25 07:49:54 INFO Shell command 'quota --always-resolve -w 5264ee526cec0e761f00006c' ran. rc=0 out=Disk quotas for user 5264ee526cec0e761f00006c (uid 1454): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01  225488       0 1048576            5418       0   40000        
>
>October 25 07:49:57 INFO Backing up 5264ee526cec0e761f00006cgear registry
>October 25 07:49:57 INFO Retrieving 5264ee526cec0e761f00006c gear registry entries prior to this update
>October 25 07:49:57 INFO Clearing 5264ee526cec0e761f00006c gear registry
>October 25 07:49:57 INFO Adding gear registry 5264ee526cec0e761f00006c new web entry: {:type=>:web, :uuid=>"5264ee526cec0e761f00006c", :namespace=>"wjiangint", :dns=>"scaledbs-wjiangint.int.rhcloud.com", :proxy_hostname=>"ex-std-node2.int.rhcloud.com", :proxy_port=>"40301"}
>October 25 07:49:57 INFO Adding gear registry 5264ee526cec0e761f00006c new web entry: {:type=>:web, :uuid=>"5264eeb56cec0e761f000091", :namespace=>"wjiangint", :dns=>"5264eeb56cec0e761f000091-wjiangint.int.rhcloud.com", :proxy_hostname=>"ex-std-node3.int.rhcloud.com", :proxy_port=>"53741"}
>October 25 07:49:57 INFO Adding gear registry 5264ee526cec0e761f00006c new web entry: {:type=>:web, :uuid=>"526a5a9903ef64b8e0000003", :namespace=>"wjiangint", :dns=>"526a5a9903ef64b8e0000003-wjiangint.int.rhcloud.com", :proxy_hostname=>"ex-std-node1.int.rhcloud.com", :proxy_port=>"50006"}
>October 25 07:49:57 INFO Adding gear registry 5264ee526cec0e761f00006c new proxy entry: {:type=>:proxy, :uuid=>"5264ee526cec0e761f00006c", :namespace=>"wjiangint", :dns=>"scaledbs-wjiangint.int.rhcloud.com", :proxy_hostname=>"ex-std-node2.int.rhcloud.com", :proxy_port=>0}
>October 25 07:49:57 INFO Saving gear registry 5264ee526cec0e761f00006c
>October 25 07:49:57 INFO Retrieving updated gear registry 5264ee526cec0e761f00006c entries
>October 25 07:49:58 INFO Shell command '/sbin/runuser -s /bin/sh 5264ee526cec0e761f00006c -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c432' /bin/sh -c \"rsync -avz --delete --rsh=/usr/bin/oo-ssh app-deployments/ 526a5a9903ef64b8e0000003@ex-std-node1.int.rhcloud.com:app-deployments/\""' ran. rc=0 out=sending incremental file list
>./
>deleting 2013-10-25_07-48-46.904/repo/
>deleting 2013-10-25_07-48-46.904/dependencies/
>deleting 2013-10-25_07-48-46.904/build-dependencies/
>deleting 2013-10-25_07-48-46.904/
>current -> 2013-10-24_19-27-39.347
>2013-10-24_19-27-39.347/
>2013-10-24_19-27-39.347/metadata.json
>2013-10-24_19-27-39.347/build-dependencies/
>2013-10-24_19-27-39.347/dependencies/
>--
>2013-10-24_19-27-39.347/repo/wsgi/__pycache__/
>2013-10-24_19-27-39.347/repo/wsgi/__pycache__/applicationcpython-33.pyc
>2013-10-24_19-27-39.347/repo/wsgi/static/
>2013-10-24_19-27-39.347/repo/wsgi/static/.gitignore
>by-id/
>by-id/f5d5b5d4 -> ../2013-10-24_19-27-39.347
>
>sent 14323 bytes  received 549 bytes  9914.67 bytes/sec
>total size is 22095  speedup is 1.49
>
>October 25 07:50:13 INFO Shell command '/sbin/runuser -s /bin/sh 5264ee526cec0e761f00006c -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c432' /bin/sh -c \"/usr/bin/oo-ssh 526a5a9903ef64b8e0000003@ex-std-node1.int.rhcloud.com gear activate f5d5b5d4 --as-json --init --no-rotation\""' ran. rc=0 out={"status":"failure","messages":["Starting application scaledbs"],"errors":["Error activating gear: Failed to execute: 'control deploy' for /var/lib/openshift/526a5a9903ef64b8e0000003/python","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1266:in `block in do_control_with_directory'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1067:in `process_cartridges'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1229:in `do_control_with_directory'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1089:in `do_control'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:820:in `activate_local_gear'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:680:in `block in activate'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:983:in `rotate_and_yield'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:947:in `block in with_gear_rotation'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:345:in `call'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:345:in `call_with_index'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:188:in `block (3 levels) in work_in_threads'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:352:in `with_instrumentation'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:186:in `block (2 levels) in work_in_threads'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:180:in `loop'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:180:in `block in work_in_threads'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:65:in `block (2 levels) in in_threads'"],"gear_uuid":"526a5a9903ef64b8e0000003","deployment_id":"f5d5b5d4"}
>
>October 25 07:50:13 INFO Activation result for gears 526a5a9903ef64b8e0000003: {:status=>"failure", :gear_results=>{"526a5a9903ef64b8e0000003"=>{"status"=>"failure", "messages"=>["Activating gear 526a5a9903ef64b8e0000003, deployment id: f5d5b5d4, --init\n", "Starting application scaledbs"], "errors"=>["Error activating gear: Failed to execute: 'control deploy' for /var/lib/openshift/526a5a9903ef64b8e0000003/python", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1266:in `block in do_control_with_directory'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1067:in `process_cartridges'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1229:in `do_control_with_directory'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1089:in `do_control'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:820:in `activate_local_gear'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:680:in `block in activate'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:983:in `rotate_and_yield'", "/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:947:in `block in with_gear_rotation'", "/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:345:in `call'", "/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:345:in `call_with_index'", "/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:188:in `block (3 levels) in work_in_threads'", "/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:352:in `with_instrumentation'", "/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:186:in `block (2 levels) in work_in_threads'", "/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:180:in `loop'", "/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:180:in `block in work_in_threads'", "/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:65:in `block (2 levels) in in_threads'"], "gear_uuid"=>"526a5a9903ef64b8e0000003", "deployment_id"=>"f5d5b5d4"}}}
>October 25 07:50:13 INFO Shell command 'quota --always-resolve -w 5264ee526cec0e761f00006c' ran. rc=0 out=Disk quotas for user 5264ee526cec0e761f00006c (uid 1454): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01  225488       0 1048576            5418       0   40000        
>
>October 25 07:50:13 INFO Restoring 5264ee526cec0e761f00006c gear registry from backup
>October 25 07:50:13 INFO 5264ee526cec0e761f00006c update-cluster against 'haproxy'
>October 25 07:50:27 INFO Shell command '/sbin/runuser -s /bin/sh 5264ee526cec0e761f00006c -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c432' /bin/sh -c \"set -e; /var/lib/openshift/5264ee526cec0e761f00006c/haproxy/bin/control update-cluster 5264eeb56cec0e761f000091-wjiangint.int.rhcloud.com'
>'\|10.196.50.80:53741\ scaledbs-wjiangint.int.rhcloud.com\|ex-std-node2.int.rhcloud.com:40301\""' ran. rc=0 out=Web/Proxy gears ratio 2
>No disabling required
>
>
>
>platform-trace.log
>
>[dmcphers@ex-std-node2.int node]$ grep -A10 -B10 '526a5a9903ef64b8e0000003' platform-trace.log 
>October 25 07:47:36 INFO oo_spawn running userdel --remove -f "526a5a0403ef64b8a70001b5": {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 13>, :err=>#<IO:fd 11>}
>October 25 07:47:37 INFO oo_spawn running setquota --always-resolve -u 526a5a0403ef64b8a70001b5 0 0 0 0 -a /var/lib/openshift: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 13>, :err=>#<IO:fd 11>}
>October 25 07:47:37 INFO oo_spawn buffer(8/) setquota: 
>October 25 07:47:37 INFO oo_spawn buffer(8/) user 526a5a0403ef64b8a70001b5 does not exist.
>
>October 25 07:49:54 INFO oo_spawn running quota --always-resolve -w 5264ee526cec0e761f00006c: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 12>, :err=>#<IO:fd 8>}
>October 25 07:49:54 INFO oo_spawn buffer(11/) Disk quotas for user 5264ee526cec0e761f00006c (uid 1454): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01  225488       0 1048576            5418       0   40000        
>
>October 25 07:49:57 INFO oo_spawn running /sbin/runuser -s /bin/sh 5264ee526cec0e761f00006c -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c432' /bin/sh -c \"rsync -avz --delete --rsh=/usr/bin/oo-ssh app-deployments/ 526a5a9903ef64b8e0000003@ex-std-node1.int.rhcloud.com:app-deployments/\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/5264ee526cec0e761f00006c/", :out=>#<IO:fd 12>, :err=>#<IO:fd 8>}
>October 25 07:49:58 INFO oo_spawn buffer(11/) sending incremental file list
>
>October 25 07:49:58 INFO oo_spawn buffer(11/) ./
>
>October 25 07:49:58 INFO oo_spawn buffer(11/) deleting 2013-10-25_07-48-46.904/repo/
>
>October 25 07:49:58 INFO oo_spawn buffer(11/) deleting 2013-10-25_07-48-46.904/dependencies/
>
>October 25 07:49:58 INFO oo_spawn buffer(11/) deleting 2013-10-25_07-48-46.904/build-dependencies/
>
>--
>October 25 07:49:58 INFO oo_spawn buffer(11/) by-id/
>
>October 25 07:49:58 INFO oo_spawn buffer(11/) by-id/f5d5b5d4 -> ../2013-10-24_19-27-39.347
>
>October 25 07:49:58 INFO oo_spawn buffer(11/) 
>
>October 25 07:49:58 INFO oo_spawn buffer(11/) sent 14323 bytes  received 549 bytes  9914.67 bytes/sec
>
>October 25 07:49:58 INFO oo_spawn buffer(11/) total size is 22095  speedup is 1.49
>
>October 25 07:49:58 INFO oo_spawn running /sbin/runuser -s /bin/sh 5264ee526cec0e761f00006c -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c432' /bin/sh -c \"/usr/bin/oo-ssh 526a5a9903ef64b8e0000003@ex-std-node1.int.rhcloud.com gear activate f5d5b5d4 --as-json --init --no-rotation\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 12>, :err=>#<IO:fd 8>}
>October 25 07:50:12 INFO oo_spawn buffer(11/) {"status":"failure","messages":["Starting application scaledbs"],"errors":["Error activating gear: Failed to execute: 'control deploy' for /var/lib/openshift/526a5a9903ef64b8e0000003/python","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1266:in `block in do_control_with_directory'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1067:in `process_cartridges'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1229:in `do_control_with_directory'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/v2_cart_model.rb:1089:in `do_control'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:820:in `activate_local_gear'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:680:in `block in activate'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:983:in `rotate_and_yield'","/opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-node-1.16.5/lib/openshift-origin-node/model/application_container_ext/cartridge_actions.rb:947:in `block in with_gear_rotation'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:345:in `call'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:345:in `call_with_index'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:188:in `block (3 levels) in work_in_threads'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:352:in `with_instrumentation'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:186:in `block (2 levels) in work_in_threads'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:180:in `loop'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:180:in `block in work_in_threads'","/opt/rh/ruby193/root/usr/share/gems/gems/parallel-0.8.0/lib/parallel.rb:65:in `block (2 levels) in in_threads'"],"gear_uuid":"526a5a9903ef64b8e0000003","deployment_id":"f5d5b5d4"}
>
>October 25 07:50:13 INFO oo_spawn running quota --always-resolve -w 5264ee526cec0e761f00006c: {:unsetenv_others=>false, :close_others=>true, :in=>"/dev/null", :out=>#<IO:fd 12>, :err=>#<IO:fd 8>}
>October 25 07:50:13 INFO oo_spawn buffer(11/) Disk quotas for user 5264ee526cec0e761f00006c (uid 1454): 
>     Filesystem  blocks   quota   limit   grace   files   quota   limit   grace
>/dev/mapper/EBSStore01-user_home01  225488       0 1048576            5418       0   40000        
>
>October 25 07:50:13 INFO oo_spawn running /sbin/runuser -s /bin/sh 5264ee526cec0e761f00006c -c "exec /usr/bin/runcon 'unconfined_u:system_r:openshift_t:s0:c1,c432' /bin/sh -c \"set -e; /var/lib/openshift/5264ee526cec0e761f00006c/haproxy/bin/control update-cluster 5264eeb56cec0e761f000091-wjiangint.int.rhcloud.com'
>'\|10.196.50.80:53741\ scaledbs-wjiangint.int.rhcloud.com\|ex-std-node2.int.rhcloud.com:40301\"": {:unsetenv_others=>true, :close_others=>true, :in=>"/dev/null", :chdir=>"/var/lib/openshift/5264ee526cec0e761f00006c/haproxy", :out=>#<IO:fd 12>, :err=>#<IO:fd 8>}
>October 25 07:50:27 INFO oo_spawn buffer(11/) Web/Proxy gears ratio 2
>
>
>
>
>production.log
>
>ptools/command/install_egg_info.py -> build/src/setuptools/command\ncopying setuptools/tests/py26compat.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_bdist_egg.py -> build/src/setuptools/tests\ncopying setuptools/tests/__init__.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_sandbox.py -> build/src/setuptools/tests\ncopying setuptools/tests/server.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_resources.py -> build/src/setuptools/tests\ncopying setuptools/tests/doctest.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_markerlib.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_upload_docs.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_easy_install.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_test.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_build_ext.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_dist_info.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_develop.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_packageindex.py -> build/src/setuptools/tests\ncopying setuptools/tests/test_sdist.py -> build/src/setuptools/tests\ncopying setuptools/tests/win_script_wrapper.txt -> build/src/setuptools/tests\ncopying setuptools/cli-64.exe -> build/src/setuptools\ncopying setuptools/cli.exe -> build/src/setuptools\ncopying setuptools/cli-arm-32.exe -> build/src/setuptools\ncopying setuptools/gui-64.exe -> build/src/setuptools\ncopying setuptools/gui.exe -> build/src/setuptools\ncopying setuptools/gui-32.exe -> build/src/setuptools\ncopying setuptools/gui-arm-32.exe -> build/src/setuptools\ncopying setuptools/cli-32.exe -> build/src/setuptools\ncopying setuptools/command/launcher manifest.xml -> build/src/setuptools/command\ncopying tests/test_pkg_resources.py -> build/src/tests\ncopying tests/test_distribute_setup.py -> build/src/tests\ncopying tests/manual_test.py -> build/src/tests\ncopying tests/install_test.py -> build/src/tests\ncopying tests/shlib_test/setup.py -> build/src/tests/shlib_test\ncopying tests/shlib_test/test_hello.py -> build/src/tests/shlib_test\ncopying tests/shlib_test/hello.c -> build/src/tests/shlib_test\ncopying tests/shlib_test/hellolib.c -> build/src/tests/shlib_test\ncopying tests/shlib_test/hello.pyx -> build/src/tests/shlib_test\ncopying tests/api_tests.txt -> build/src/tests\n--- build/src/tests/api_tests.txt\t(original)\n+++ build/src/tests/api_tests.txt\t(refactored)\n@@ -39,7 +39,7 @@\n     >>> dist.py_version == sys.version[:3]\n     True\n \n-    >>> print dist.platform\n+    >>> print(dist.platform)\n     None\n \n Including various computed attributes::\n@@ -199,7 +199,7 @@\n You can ask a WorkingSet to ``find()`` a distribution matching a requirement::\n \n     >>> from pkg_resources import Requirement\n-    >>> print ws.find(Requirement.parse(\"Foo==1.0\"))    # no match, return None\n+    >>> print(ws.find(Requirement.parse(\"Foo==1.0\")))    # no match, return None\n     None\n \n     >>> ws.find(Requirement.parse(\"Bar==0.9\"))  # match, return distribution\n@@ -211,7 +211,7 @@\n     >>> try:\n     ...     ws.find(Requirement.parse(\"Bar==1.0\"))\n     ... except VersionConflict:\n-    ...     print 'ok'\n+    ...     print('ok')\n     ok\n \n You can subscribe a callback function to receive notifications whenever a new\n@@ -219,7 +219,7 @@\n once for each existing distribution in the working set, and then is called\n again for new distributions added thereafter::\n \n-    >>> def added(dist): print \"Added\", dist\n+    >>> def added(dist): print(\"Added\", dist)\n     >>> ws.subscribe(added)\n     Added Bar 0.9\n     >>> foo12 = Distribution(project_name=\"Foo\", version=\"1.2\", location=\"f12\") \ncopying setuptools/tests/indexes/test_links_priority/external.html -> build/src/setuptools/tests/indexes/test_links_priority\ncopying setuptools/tests/indexes/test_links_priority/simple/foobar/index.html -> build/src/setuptools/tests/indexes/test_links_priority/simple/foobar\ncopying docs/conf.py -> build/src/docs\ncopying docs/python3.txt -> build/src/docs\ncopying docs/easy_install.txt -> build/src/docs\ncopying docs/roadmap.txt -> build/src/docs\ncopying docs/index.txt -> build/src/docs\ncopying docs/pkg_resources.txt -> build/src/docs\ncopying docs/setuptools.txt -> build/src/docs\ncopying docs/using.txt -> build/src/docs\ncopying docs/build/html/_sources/python3.txt -> build/src/docs/build/html/_sources\ncopying docs/build/html/_sources/easy_install.txt -> build/src/docs/build/html/_sources\ncopying docs/build/html/_sources/roadmap.txt -> build/src/docs/build/html/_sources\ncopying docs/build/html/_sources/index.txt -> build/src/docs/build/html/_sources\ncopying docs/build/html/_sources/pkg_resources.txt -> build/src/docs/build/html/_sources\ncopying docs/build/html/_sources/setuptools.txt -> build/src/docs/build/html/_sources\ncopying docs/build/html/_sources/using.txt -> build/src/docs/build/html/_sources\ncopying docs/_theme/nature/theme.conf -> build/src/docs/_theme/nature\ncopying docs/build/html/_static/basic.css -> build/src/docs/build/html/_static\ncopying docs/build/html/_static/pygments.css -> build/src/docs/build/html/_static\ncopying docs/build/html/_static/nature.css -> build/src/docs/build/html/_static\ncopying docs/_theme/nature/static/pygments.css -> build/src/docs/_theme/nature/static\ncopying docs/_theme/nature/static/nature.css_t -> build/src/docs/_theme/nature/static\ncopying docs/Makefile -> build/src/docs\ncopying docs/_templates/indexsidebar.html -> build/src/docs/_templates\ncopying _markerlib/markers.py -> build/src/_markerlib\ncopying _markerlib/__init__.py -> build/src/_markerlib\ncopying setup.py -> build/src\ncopying release.py -> build/src\ncopying easy_install.py -> build/src\ncopying pkg_resources.py -> build/src\ncopying distribute_setup.py -> build/src\ncopying CONTRIBUTORS.txt -> build/src\ncopying DEVGUIDE.txt -> build/src\ncopying README.txt -> build/src\ncopying CHANGES.txt -> build/src\ncopying CHANGES (links).txt -> build/src\ncopying MANIFEST.in -> build/src\ncopying launcher.c -> build/src\nSkipping implicit fixer: buffer\nSkipping implicit fixer: idioms\nSkipping implicit fixer: set_literal\nSkipping implicit fixer: ws_comma\nrunning install\nrunning bdist_egg\nrunning egg_info\nwriting distribute.egg-info/PKG-INFO\nwriting top-level names to distribute.egg-info/top_level.txt\nwriting dependency_links to distribute.egg-info/dependency_links.txt\nwriting entry points to distribute.egg-info/entry_points.txt\nreading manifest file 'distribute.egg-info/SOURCES.txt'\nreading manifest template 'MANIFEST.in'\nwriting manifest file 'distribute.egg-info/SOURCES.txt'\ninstalling library code to build/bdist.linux-x86_64/egg\nrunning install_lib\nrunning build_py\ncreating build/lib\ncopying build/src/pkg_resources.py -> build/lib\ncopying build/src/easy_install.py -> build/lib\ncreating build/lib/_markerlib\ncopying build/src/_markerlib/markers.py -> build/lib/_markerlib\ncopying build/src/_markerlib/__init__.py -> build/lib/_markerlib\ncreating build/lib/setuptools\ncopying build/src/setuptools/archive_util.py -> build/lib/setuptools\ncopying build/src/setuptools/py24compat.py -> build/lib/setuptools\ncopying build/src/setuptools/script template.py -> build/lib/setuptools\ncopying build/src/setuptools/depends.py -> build/lib/setuptools\ncopying build/src/setuptools/__init__.py -> build/lib/setuptools\ncopying build/src/setuptools/extension.py -> build/lib/setuptools\ncopying build/src/setuptools/dist.py -> build/lib/setuptools\ncopying build/src/setuptools/package_index.py -> build/lib/setuptools\ncopying build/src/setuptools/sandbox.py -> build/lib/setuptools\ncopying build/src/setuptools/script template (dev).py -> build/lib/setuptools\ncopying build/src/setuptools/site-patch.py -> build/lib/setuptools\ncreating build/lib/setuptools/tests\ncopying build/src/setuptools/tests/py26compat.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_bdist_egg.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/__init__.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_sandbox.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/server.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_resources.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/doctest.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_markerlib.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_upload_docs.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_easy_install.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_test.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_build_ext.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_dist_info.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_develop.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_packageindex.py -> build/lib/setuptools/tests\ncopying build/src/setuptools/tests/test_sdist.py -> build/lib/setuptools/tests\ncreating build/lib/setuptools/command\ncopying build/src/setuptools/command/rotate.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/bdist_egg.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/test.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/alias.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/easy_install.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/sdist.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/setopt.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/bdist_rpm.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/install_scripts.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/upload.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/__init__.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/build_py.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/develop.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/build_ext.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/upload_docs.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/saveopts.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/register.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/install_lib.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/bdist_wininst.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/egg_info.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/install.py -> build/lib/setuptools/command\ncopying build/src/setuptools/command/install_egg_info.py -> build/lib/setuptools/command\ncopying build/src/setuptools/cli-64.exe -> build/lib/setuptools\ncopying build/src/setuptools/cli.exe -> build/lib/setuptools\ncopying build/src/setuptools/cli-arm-32.exe -> build/lib/setuptools\ncopying build/src/setuptools/gui-64.exe -> build/lib/setuptools\ncopying build/src/setuptools/gui.exe -> build/lib/setuptools\ncopying build/src/setuptools/gui-32.exe -> build/lib/setuptools\ncopying build/src/setuptools/gui-arm-32.exe -> build/lib/setuptools\ncopying build/src/setuptools/cli-32.exe -> build/lib/setuptools\ncopying build/src/setuptools/command/launcher manifest.xml -> build/lib/setuptools/command\ncreating build/bdist.linux-x86_64\ncreating build/bdist.linux-x86_64/egg\ncopying build/lib/easy_install.py -> build/bdist.linux-x86_64/egg\ncopying build/lib/pkg_resources.py -> build/bdist.linux-x86_64/egg\ncreating build/bdist.linux-x86_64/egg/_markerlib\ncopying build/lib/_markerlib/markers.py -> build/bdist.linux-x86_64/egg/_markerlib\ncopying build/lib/_markerlib/__init__.py -> build/bdist.linux-x86_64/egg/_markerlib\ncreating build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/cli-64.exe -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/archive_util.py -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/cli.exe -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/cli-arm-32.exe -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/py24compat.py -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/script template.py -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/depends.py -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/__init__.py -> build/bdist.linux-x86_64/egg/setuptools\ncreating build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/py26compat.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_bdist_egg.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/__init__.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_sandbox.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/server.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_resources.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/doctest.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_markerlib.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_upload_docs.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_easy_install.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_test.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_build_ext.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_dist_info.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_develop.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_packageindex.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/tests/test_sdist.py -> build/bdist.linux-x86_64/egg/setuptools/tests\ncopying build/lib/setuptools/gui-64.exe -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/extension.py -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/gui.exe -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/dist.py -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/package_index.py -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/sandbox.py -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/gui-32.exe -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/script template (dev).py -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/gui-arm-32.exe -> build/bdist.linux-x86_64/egg/setuptools\ncreating build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/rotate.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/bdist_egg.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/launcher manifest.xml -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/test.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/alias.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/easy_install.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/sdist.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/setopt.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/bdist_rpm.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/install_scripts.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/upload.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/__init__.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/build_py.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/develop.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/build_ext.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/upload_docs.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/saveopts.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/register.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/install_lib.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/bdist_wininst.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/egg_info.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/install.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/command/install_egg_info.py -> build/bdist.linux-x86_64/egg/setuptools/command\ncopying build/lib/setuptools/site-patch.py -> build/bdist.linux-x86_64/egg/setuptools\ncopying build/lib/setuptools/cli-32.exe -> build/bdist.linux-x86_64/egg/setuptools\nbyte-compiling build/bdist.linux-x86_64/egg/easy_install.py to easy_install.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/pkg_resources.py to pkg_resources.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/_markerlib/markers.py to markers.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/_markerlib/__init__.py to __init__.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/archive_util.py to archive_util.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/py24compat.py to py24compat.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/script template.py to script template.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/depends.py to depends.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/__init__.py to __init__.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/py26compat.py to py26compat.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_bdist_egg.py to test_bdist_egg.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/__init__.py to __init__.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_sandbox.py to test_sandbox.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/server.py to server.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_resources.py to test_resources.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/doctest.py to doctest.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_markerlib.py to test_markerlib.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_upload_docs.py to test_upload_docs.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_easy_install.py to test_easy_install.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_test.py to test_test.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_build_ext.py to test_build_ext.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_dist_info.py to test_dist_info.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_develop.py to test_develop.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_packageindex.py to test_packageindex.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/tests/test_sdist.py to test_sdist.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/extension.py to extension.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/dist.py to dist.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/package_index.py to package_index.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/sandbox.py to sandbox.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/script template (dev).py to script template (dev).cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/rotate.py to rotate.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/bdist_egg.py to bdist_egg.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/test.py to test.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/alias.py to alias.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/easy_install.py to easy_install.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/sdist.py to sdist.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/setopt.py to setopt.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/bdist_rpm.py to bdist_rpm.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/install_scripts.py to install_scripts.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/upload.py to upload.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/__init__.py to __init__.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/build_py.py to build_py.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/develop.py to develop.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/build_ext.py to build_ext.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/upload_docs.py to upload_docs.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/saveopts.py to saveopts.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/register.py to register.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/install_lib.py to install_lib.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/bdist_wininst.py to bdist_wininst.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/egg_info.py to egg_info.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/install.py to install.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/command/install_egg_info.py to install_egg_info.cpython-33.pyc\nbyte-compiling build/bdist.linux-x86_64/egg/setuptools/site-patch.py to site-patch.cpython-33.pyc\ncreating build/bdist.linux-x86_64/egg/EGG-INFO\ncopying distribute.egg-info/PKG-INFO -> build/bdist.linux-x86_64/egg/EGG-INFO\ncopying distribute.egg-info/SOURCES.txt -> build/bdist.linux-x86_64/egg/EGG-INFO\ncopying distribute.egg-info/dependency_links.txt -> build/bdist.linux-x86_64/egg/EGG-INFO\ncopying distribute.egg-info/entry_points.txt -> build/bdist.linux-x86_64/egg/EGG-INFO\ncopying distribute.egg-info/top_level.txt -> build/bdist.linux-x86_64/egg/EGG-INFO\ncopying distribute.egg-info/zip-safe -> build/bdist.linux-x86_64/egg/EGG-INFO\ncreating dist\ncreating 'dist/distribute-0.6.49-py3.3.egg' and adding 'build/bdist.linux-x86_64/egg' to it\nremoving 'build/bdist.linux-x86_64/egg' (and everything under it)\nProcessing distribute-0.6.49-py3.3.egg\ncreating /var/lib/openshift/526a5a9903ef64b8e0000003/app-root/runtime/dependencies/python/virtenv/venv/lib/python3.3/site-packages/distribute-0.6.49-py3.3.egg\nExtracting distribute-0.6.49-py3.3.egg to /var/lib/openshift/526a5a9903ef64b8e0000003/app-root/runtime/dependencies/python/virtenv/venv/lib/python3.3/site-packages\nAdding distribute 0.6.49 to easy-install.pth file\nInstalling easy_install script to /var/lib/openshift/526a5a9903ef64b8e0000003/python/virtenv/venv/bin\nInstalling easy_install-3.3 script to /var/lib/openshift/526a5a9903ef64b8e0000003/python/virtenv/venv/bin\n\nInstalled /var/lib/openshift/526a5a9903ef64b8e0000003/app-root/runtime/dependencies/python/virtenv/venv/lib/python3.3/site-packages/distribute-0.6.49-py3.3.egg\nProcessing dependencies for distribute==0.6.49\nFinished processing dependencies for distribute==0.6.49\nSearching for pip\nReading http://pypi.python.org/simple/pip/\nBest match: pip 1.4.1\nDownloading https://pypi.python.org/packages/source/p/pip/pip-1.4.1.tar.gz#md5=6afbb46aeb48abac658d4df742bff714\nProcessing pip-1.4.1.tar.gz\nWriting /tmp/easy_install-dzs46l/pip-1.4.1/setup.cfg\nRunning pip-1.4.1/setup.py -q bdist_egg --dist-dir /tmp/easy_install-dzs46l/pip-1.4.1/egg-dist-tmp-pk29kz\nAdding pip 1.4.1 to easy-install.pth file\nInstalling pip-3.3 script to /var/lib/openshift/526a5a9903ef64b8e0000003/python/virtenv/venv/bin\nInstalling pip script to /var/lib/openshift/526a5a9903ef64b8e0000003/python/virtenv/venv/bin\n\nInstalled /var/lib/openshift/526a5a9903ef64b8e0000003/app-root/runtime/dependencies/python/virtenv/venv/lib/python3.3/site-packages/pip-1.4.1-py3.3.egg\nProcessing dependencies for pip\nFinished processing dependencies for pip\n", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:49:49.398 [DEBUG] DEBUG: MCollective Response Time (execute_direct: configure): 54.477013377s  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:49:54.428 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAyGc2LTdj2jL7D/Wpq87eCUXZ0SOTsD+QRBMNdwk16TiQV3nClZpdOKdOWE1HBkuGukw+IFAvHDJoTaqL/F/wTF5TxxJwF8exbCKbDOFk9EMTeYzEDtpPL7CKStGwO6vMpOiLwa4VN4f34q1O9LE6d9++BdaELAlobReDXMPT+TqqnBO9BIMFFPIMbFrT6iKdupKn9zRc1nTzu03qMJalOsC3P1Frs2JZHmwYe3gUn8PGhb+tvFLZIaPm8GAuy284bYcDbVgmZsEEiGmlE7GPTxV6Jgo5xNa7sGP/iL7kIZxFX/oUEfs3ivQXHHvud3EybohllZmSWGw2FrSPtLoCZw==", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"application-5264ee526cec0e761f00006c"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAxP4hJ+mRxHc5cW/iOVJ+lDRRl2OfJVkZq4wpYKumcdwKWt4gLdv0OAGnlZ2z/yZ4CF+8wZKhspkaOoU/mPsRy1Fo6UqEqARZ0v5PeH4q0X4qPveJBXL/UbM+OZXmZawa9CgSNA8qpTiOvjGcFPE1Glh5BMP7Q8e1fQ42zh6ngrVl+MMx56OwIBG7yrstIFnTPeGskZqHwRKwC1xRY+ZI++NCWFsnzqtofq+F0JHUKEZ+g0igprZbnxpiF0nuqzj9X847PYXm9SK0V1XeBtuIJUbAKEtHetge0VDXE9QSxPAzcPyWKrcFENuzMYK4IFfZoPtr4M06ukwN9Lf7ha6JXQ==", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"application-5264eeb56cec0e761f000091"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAx7evyR6qmLMoN1eKrGFH1kELsYJ6ExBkP28G6JhT6j5GtBe8h1mwR4dsCFjtkNKEMXr07tkvvNiVPibxVNbM19ZWJaJO7Px81PCddZScWaQnsH8HgjsmW5wJHwQlXDW55D8Wa1SY2/KPxWTXMnSf927xxhzQ4xVt0G3LFwegnnw330fh5OoJFYOVS+dM2AjDynuCOOYoDtNO/yGXUqVaN116v9kfQYeURB4zpkJ0M6R6RgH7fKY9EV3gCNJDuu/gMXiFkhlMyxIVlQyRdlU5oGMwNyJ5lOhXtvKsJOiJFmSM5GzfY5lxhMIGxnLn2jzI9GcpU8eMoeJvmNmHIg/Gpw==", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"application-5264ef1c03ef64e64e0000b7"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEArdGKy3NHJzNF8zkoQhDeMuRHwsz1WUEtVcFYtK1Fna1qv85gT+ln6c+x6ZhOXJRRgFwwLnh5OrAHjU3KT+FV8a1/EYjlVq/oVamR9iskCtPc3LeXRA3jMcsfpQxzfltUQHpuk+BisiieX/nEsi82xgX2oyeUZS8fQq02fPAMLb0knN9ba4TJX9uk1ZLUCBEEaAm4xkBGlQQvOeABXT1wMJuF2ig480pSvES/UUqfchzvaTddYdMh85+sXrld9IlpszxV2ny4pOmT02J5s3LaJaJN+G+oCpp4e66n1v2HwK7TwwRykTWYOaqZN4NprRFkLx0BqpvHW3apdGrdirNFWw==", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"application-5264efe76cec0e478b00006e"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAvYrOdeRNw6OSwmMkDLbCq3dw0zS89Um2Lrd3qKaJ3k14WB3FlHEPzBgIa5cWKgfoIsde0fjSISG9Ef1Z6oLvfu2YhKwxnjfIZwViDPdSZBCF32qn8lLBQnMJ3P5olrhhll04p/wa3daOOtERM5XmuTFxSWOm2RnHypMIaSy1nGeDOQSnQAc0fTSZDb6Uje4Ml0BeSvgmKtl+qPOp9u/rqm2pUfLVIQIIrLltaDKcZJ0QzZ9g/Po070s3tcUviPi2imn5uPd+b0v2w9FZMXw/gHzorf0veyDkoLCQxG+kjlnpNwisX0RXhAnZrJyCtKws+7O42TaZxfNykyZj+d8U6w==", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"application-5264f01203ef64e64e0000e8"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEA0Z4u/UojW40+BOAMi63weXKRWmfdBd8fvyuEI+g2iTyGEKWk7W3pkBe2RqekZANfzVf1Ig21WvqAZWV90brRimOCUuvelKQnCn7yn2wFxRHxan4DAwCNadpuEpmljtfri4INoa5m0x+xYz0kxH+K0Z1lQuLQpaUzpFJ682e0b5/VaRM6Dp46EIwAoVugtgbk8fpiP3d1B2AMxOgW3BLCqJFb1uwdfwuDEL3Ia+wE2JYiue0K14tO7Qr5XlSM2Yy4UBZCtVMKBjxvGDUS5NtKhttmw66yeDkqOWu9EwqqD10UH0s2rvHpRVDxNDY2WyDQCEQhXLFXFMPxOSnkGhpryQ==", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"application-5264f1b46cec0e478b0000d0"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAwE2bWLBxYK4W3dYlxJkxysWb1mfJ2nct9RC23NSYXRdLqp+/3b+QxozyNixbq8lXP4Y79gewpE3Sgs4Pbdw+786UNlnoyX1q/lWsvVCRcmv/roEeAh5vN0QYJ5kVCzaBVl8Ft+CBvHZtWu8W6/nOuK6bZb/UwnfcT0/pA2sJDb8HgOeLyLm4uVZLh4Zn8Lmhxe1uZbPWqyEzp5W4Ed4GTJZ/GBGZg6NbLLHNnqym8Qp3dkrNEiK8xORLUB+CqdTN8rZ+zc6lfy+Ug1/CmMb8o0A6Bxg4SBJfShO60khVBA0o3VmlE/aqVUCTCVyYZ7nv+Xu8TxOjTy1rNVqlVPY/xw==", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"application-526a5a9903ef64b8e0000003"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAzEZ1+yJNCjJa5M5yzE1ilP/CsEJT5PYjMQphFRFmwmiNatlSNxH6fbdGJeZV6PD1IEVONHu9hQtFtmreLq2a8tjfEi1sabUj/Da4P5owChL6j6UigNZLbgehHwdjiQHYhXDDFVTDTASYVEPjsDvVn4mGwd4Z4IfuW31Sva+2Wf2hZYpRUrQg2Z5bKUIXw5zVZ4rhE32blWXLOr35C7CfOs7yCX9p2UVlEaeGPhmelWb3k/GLRTF1WMoA+xKkUjc90RfoEwF4FDzmCGAn6wFp9ugUMKrxJre3A+uTd/ABWcp1q+wXFtOFsNpzJ5LDv1G7DIbZXYFlnxsxI8jZNTVdCQ==", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"domain-jenkins"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQC7aEfCQQhFja2rEMEdNt2SSF5dcPOQPd9g61e6AuDQtI/t687TL0HsUcD+7G/VFmB5PyMbogflR19KupHno2t2/Q/zd7Nv9q+5BXeMXKJGr8IaINU1MIjyd4EWOU32mmosv9z87oQDvY1u2s15I01pBqWmv2r/DeZEKDlbLQbQ2Jckinh3Ufs7Lj7HFGPUi5DaujTg5x5IYAfyezSuIhbBZ3C0Ol59O+vsXQSkb23AKDVs+J/Ep60vwAUz1MVPFKykayOrAa23NeoVYkEuiwvH4BLLQrZYatyl2zXs85B3ivAjIJ02WB26fsVNnb9hMyZopMY9kGl87JaY3MC/Y36f", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"5260af1503ef64cdce000081-default"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQDBKBQzetMqif3kYcabnckEhd/RBD/Kk5MZUUBRRx5OjvCedUh1GuJM4eI1aKiD5QxUp0NCXUFORmaF5DpcC8A67bjvxibZUEMunEUps4cR26Axzox3bxq3qrzvk2jk2kMxTBe1u7gocwgtbQJw4oXgFWZid8bvIkgyZpgkXFVWWivbPjYV2O+IrLpsIMtynV3UAI0ep3fZp6vmGLaUeSexp9MFWE+4cu83lbM6Fjt3kFPPNn0znWxkfF69tELYxMPUePysjkUWaCQc5/HEPaHqQidAxDSAZyKBka66s0kMcC6Ox8YH+gFtl3LBq8I4foKP3ocFmTEIIoMyv8XADVQ/", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"5260af1503ef64cdce000081-asdfasdf"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAABIwAAAQEAylY7pk0ajJ7ur084RpMgjlQEHkAYGrIKrnoiU7k1ShhZTUR/+nL/yBKYH0ikAn98jEKlvDjD9FilMhoo3WnWL9XYyGL2FxfRB2ly+W9602Lpa5YBkSZiiZTKiXFNRx3k/L2kUeoCWi1Q+vdzLntpHyXdJfyFHIxrwA6y3j3vqB+g2iBIj6LQ90gC5sZMhVPmn5fPRGFHN+Y5uwR+0p/gwsRLOmTsOCeWx/HEKq8+x4wGGfbOrs2g0ewXwrWjXvSXmvkiAnpbmWWanvpN4H62caZqXFnygsCpm1ZjhRZBRV4UT5sVFvRIwSKUddNmpBpywepvyb8mweuELXFlsnN53w==", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"5260af1503ef64cdce000081-adfasdf"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"authorized-ssh-key-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-ssh-key"=>"AAAAB3NzaC1yc2EAAAADAQABAAABAQCrBtLOUQfCo4x/DU0F3nb/3CEPMFo3mjBMn1trKcAQJjqHsPWFmiFrwjnkaxizlblsJysHIjSQqGkfLGvMFRb9ZUGCAfKWKUfBGjEMBsEYYegJUv1+cKRuwMq9VZo/TbzBiIU90uBo1J5uuuOJGYsMWaLYWs78QBNKtWnGP0tvv+YuAta5qiGN25vhpPtwwELqsgFpc7yf57Ly98tBoUcEpZloyCO0ryJxG5IV8krsQLeULGdbprOjFL6utZnT68bjg1AxplsLEDSCxdlcdUTBrTCIqobm/gLP2AHKTW0zEkMHQ2tBN6Z11rW7ycBLc63U0ILD+/bmu623/7Fuy3DN", "--with-ssh-key-type"=>"ssh-rsa", "--with-ssh-key-comment"=>"5260af1503ef64cdce000081-y"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"env-var-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-key"=>"JENKINS_URL", "--with-value"=>"https://jenkins-wjiangint.int.rhcloud.com/"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"env-var-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-key"=>"JENKINS_USERNAME", "--with-value"=>"system_builder"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}, {:tag=>{"op_id"=>"526a5a9903ef64b8e0000008"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"env-var-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-key"=>"JENKINS_PASSWORD", "--with-value"=>"bhrlSqTE9Ejy"}}, :result_stdout=>"", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ex-std-node1.int.rhcloud.com  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:49:54.433 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 4.994486618s  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:49:54.489 [DEBUG] Execute PatchUserEnvVarsOp (pid:28209)
>2013-10-25 07:49:54.679 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"user-var-add", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"5264ee526cec0e761f00006c", "--with-container-name"=>"scaledbs", "--with-namespace"=>"wjiangint", "--with-uid"=>1454, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--with-gears"=>"5264eeb56cec0e761f000091@ex-std-node3.int.rhcloud.com;526a5a9903ef64b8e0000003@ex-std-node1.int.rhcloud.com;5264efe76cec0e478b00006e@ex-std-node2.int.rhcloud.com;5264f01203ef64e64e0000e8@ex-std-node3.int.rhcloud.com;5264f1b46cec0e478b0000d0@ex-std-node2.int.rhcloud.com", "--cart-name"=>"openshift-origin-node"}}, ex-std-node2.int.rhcloud.com, {'identity' => ex-std-node2.int.rhcloud.com}) (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:49:54.794 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000007d17cf0 @agent="openshift", @action="cartridge_do", @results={:sender=>"ex-std-node2.int.rhcloud.com", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:49:54.795 [DEBUG] DEBUG: MCollective Response Time (execute_direct: user-var-add): 0.118509018s  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:49:54.806 [DEBUG] Execute ExposePortOp (pid:28209)
>2013-10-25 07:49:54.819 [DEBUG] Execute ExposePortOp (pid:28209)
>2013-10-25 07:49:54.830 [DEBUG] Execute ExposePortOp (pid:28209)
>2013-10-25 07:49:56.650 [DEBUG] DEBUG: Output of parallel execute: [{:tag=>{"expose-ports"=>"5264ee526cec0e761f000085", "op_id"=>"526a5a9903ef64b8e000000d"}, :gear=>"526a5a9903ef64b8e0000003", :job=>{:cartridge=>"openshift-origin-node", :action=>"expose-port", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--cart-name"=>"python-3.3", "--component-name"=>"python-3.3", "--with-software-version"=>"3.3", "--cartridge-vendor"=>"redhat"}}, :result_stdout=>"NOTIFY_ENDPOINT_CREATE: {\"external_address\":\"50.19.66.196\",\"external_port\":50006,\"internal_address\":\"127.6.161.129\",\"internal_port\":8080,\"protocols\":[\"http\",\"ws\"],\"description\":null,\"type\":[\"web_framework\"],\"mappings\":[{\"frontend\":\"\",\"backend\":\"\"},{\"frontend\":\"/health\",\"backend\":\"\"}]}\n", :result_stderr=>"", :result_exit_code=>0, :result_addtl_params=>nil}], exitcode: 0, from: ex-std-node1.int.rhcloud.com  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:49:56.651 [DEBUG] DEBUG: MCollective Response Time (execute_parallel): 1.805463604s  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:49:56.719 [DEBUG] Execute UpdateClusterOp (pid:28209)
>2013-10-25 07:49:57.167 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"update-cluster", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"5264ee526cec0e761f00006c", "--with-container-name"=>"scaledbs", "--with-namespace"=>"wjiangint", "--with-uid"=>1454, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--proxy-gears"=>"5264ee526cec0e761f00006c,scaledbs,wjiangint,ex-std-node2.int.rhcloud.com", "--web-gears"=>"5264ee526cec0e761f00006c,scaledbs,wjiangint,ex-std-node2.int.rhcloud.com,40301 5264eeb56cec0e761f000091,5264eeb56cec0e761f000091,wjiangint,ex-std-node3.int.rhcloud.com,53741 526a5a9903ef64b8e0000003,526a5a9903ef64b8e0000003,wjiangint,ex-std-node1.int.rhcloud.com,50006", "--cart-name"=>"openshift-origin-node"}}, ex-std-node2.int.rhcloud.com, {'identity' => ex-std-node2.int.rhcloud.com}) (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:50:01.394 [INFO ] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-10-25 07:50:01 -0400 (pid:19354)
>2013-10-25 07:50:01.396 [INFO ] Processing by ApiController#show as JSON (pid:19354)
>2013-10-25 07:50:01.396 [DEBUG] API version 1.6 (pid:19354)
>2013-10-25 07:50:01.407 [INFO ] Completed 200 OK in 11ms (Views: 5.7ms) (pid:19354)
>2013-10-25 07:50:04.975 [INFO ] Started GET "/" for  at 2013-10-25 07:50:04 -0400 (pid:25813)
>2013-10-25 07:50:04.977 [FATAL] ActionController::RoutingError (No route matches [GET] "/"):
>  actionpack (3.2.8) lib/action_dispatch/middleware/debug_exceptions.rb:21:in `call'
>  actionpack (3.2.8) lib/action_dispatch/middleware/show_exceptions.rb:56:in `call'
>  railties (3.2.8) lib/rails/rack/logger.rb:26:in `call_app'
>  railties (3.2.8) lib/rails/rack/logger.rb:16:in `call'
>--
>2013-10-25 07:50:27.837 [DEBUG] Parallel rollback not implemented: ExposePortOp (pid:28209)
>2013-10-25 07:50:27.837 [DEBUG] Rollback ExposePortOp (pid:28209)
>2013-10-25 07:50:27.837 [DEBUG] Parallel rollback not implemented: ExposePortOp (pid:28209)
>2013-10-25 07:50:27.837 [DEBUG] Rollback ExposePortOp (pid:28209)
>2013-10-25 07:50:27.837 [DEBUG] Parallel rollback not implemented: ExposePortOp (pid:28209)
>2013-10-25 07:50:27.907 [DEBUG] Rollback UpdateAppConfigOp (pid:28209)
>2013-10-25 07:50:27.907 [DEBUG] Parallel rollback not implemented: UpdateAppConfigOp (pid:28209)
>2013-10-25 07:50:27.907 [DEBUG] Rollback AddCompOp (pid:28209)
>2013-10-25 07:50:27.909 [DEBUG] DEBUG: Deconfiguring cartridge 'python-3.3' in application 'scaledbs' on node 'ex-std-node1.int.rhcloud.com' (pid:28209)
>2013-10-25 07:50:27.909 [DEBUG] DEBUG: Sending to Nurture:application: app_uuid='5264ee526cec0e761f00006c' action='deconfigure' (pid:28209)
>2013-10-25 07:50:27.956 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"python-3.3", :action=>"deconfigure", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--cart-name"=>"python-3.3", "--component-name"=>"python-3.3", "--with-software-version"=>"3.3", "--cartridge-vendor"=>"redhat"}}, ex-std-node1.int.rhcloud.com, {'identity' => ex-std-node1.int.rhcloud.com}) (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:50:31.256 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x000000080a2348 @agent="openshift", @action="cartridge_do", @results={:sender=>"ex-std-node1.int.rhcloud.com", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:50:31.257 [DEBUG] DEBUG: MCollective Response Time (execute_direct: deconfigure): 3.303641082s  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:50:31.327 [DEBUG] Rollback RegisterDnsOp (pid:28209)
>2013-10-25 07:50:31.329 [DEBUG] DYNECT Login with path: /REST/Session/ (pid:28209)
>2013-10-25 07:50:31.445 [INFO ] Started GET "/broker/rest/api.json" for 127.0.0.1 at 2013-10-25 07:50:31 -0400 (pid:19354)
>2013-10-25 07:50:31.447 [INFO ] Processing by ApiController#show as JSON (pid:19354)
>2013-10-25 07:50:31.447 [DEBUG] API version 1.6 (pid:19354)
>2013-10-25 07:50:31.457 [INFO ] Completed 200 OK in 10ms (Views: 5.6ms) (pid:19354)
>2013-10-25 07:50:31.979 [DEBUG] DYNECT Response: {"status": "success", "data": {"token": "kyR6Xn31BBXBtW1KVTEpbPL3f+CcwaJkzIxoqNhng1Hj0B1c1uM91+ZRVoKHFPZj6XyZaduWAQ2KiUQd48gi0zWDncTmW+fwnpe8WUFcT8cP+mxzqRJMQE3hPmCNOmuoD8YFmiTxGLL7yBh3LR7TDvmEAm1HcaVT", "version": "3.5.3"}, "job_id": 537238252, "msgs": [{"INFO": "login: Login successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:28209)
>2013-10-25 07:50:31.979 [DEBUG] DYNECT Response data: {"token"=>"kyR6Xn31BBXBtW1KVTEpbPL3f+CcwaJkzIxoqNhng1Hj0B1c1uM91+ZRVoKHFPZj6XyZaduWAQ2KiUQd48gi0zWDncTmW+fwnpe8WUFcT8cP+mxzqRJMQE3hPmCNOmuoD8YFmiTxGLL7yBh3LR7TDvmEAm1HcaVT", "version"=>"3.5.3"} (pid:28209)
>2013-10-25 07:50:31.979 [DEBUG] Dynect Response Time (dyn_login): 0.650550783s  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:50:31.980 [DEBUG] DYNECT delete with path: /REST/CNAMERecord/int.rhcloud.com/526a5a9903ef64b8e0000003-wjiangint.int.rhcloud.com/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:28209)
>2013-10-25 07:50:32.507 [DEBUG] DYNECT Response: {"status": "success", "data": {}, "job_id": 537238273, "msgs": [{"INFO": "delete: 1 records deleted", "SOURCE": "API-B", "ERR_CD": null, "LVL": "INFO"}]} (pid:28209)
>2013-10-25 07:50:32.508 [DEBUG] DYNECT Response data: {} (pid:28209)
>2013-10-25 07:50:32.508 [DEBUG] Dynect Response Time (dyn_delete): 0.527934526s  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:50:32.508 [DEBUG] DYNECT put/post with path: /REST/Zone/int.rhcloud.com/ json data: {"publish":"true"} and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:28209)
>2013-10-25 07:50:33.199 [DEBUG] DYNECT Response: {"status": "success", "data": {"zone_type": "Primary", "serial_style": "day", "serial": 2013220727, "zone": "int.rhcloud.com"}, "job_id": 537238302, "msgs": [{"INFO": "publish: int.rhcloud.com published", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:28209)
>2013-10-25 07:50:33.199 [DEBUG] DYNECT Response data: {"zone_type"=>"Primary", "serial_style"=>"day", "serial"=>2013220727, "zone"=>"int.rhcloud.com"} (pid:28209)
>2013-10-25 07:50:33.199 [DEBUG] Dynect Response Time (dyn_put_post): 0.691234465s  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:50:33.199 [DEBUG] DYNECT delete with path: /REST/Session/ and headers: {"Content-Type"=>"application/json", "Auth-Token"=>"[hidden]"} (pid:28209)
>2013-10-25 07:50:33.647 [DEBUG] DYNECT Response: {"status": "success", "data": {}, "job_id": 537238329, "msgs": [{"INFO": "logout: Logout successful", "SOURCE": "BLL", "ERR_CD": null, "LVL": "INFO"}]} (pid:28209)
>2013-10-25 07:50:33.647 [DEBUG] DYNECT Response data: {} (pid:28209)
>2013-10-25 07:50:33.647 [DEBUG] Dynect Response Time (dyn_delete): 0.448082514s  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:50:33.737 [DEBUG] Rollback CreateGearOp (pid:28209)
>2013-10-25 07:50:33.740 [DEBUG] DEBUG: rpc_client.custom_request('cartridge_do', {:cartridge=>"openshift-origin-node", :action=>"app-destroy", :args=>{"--with-app-uuid"=>"5264ee526cec0e761f00006c", "--with-app-name"=>"scaledbs", "--with-container-uuid"=>"526a5a9903ef64b8e0000003", "--with-container-name"=>"526a5a9903ef64b8e0000003", "--with-namespace"=>"wjiangint", "--with-uid"=>3395, "--with-request-id"=>"7483f644611a17f6b7c39613a63ff51c", "--cart-name"=>"openshift-origin-node"}}, ex-std-node1.int.rhcloud.com, {'identity' => ex-std-node1.int.rhcloud.com}) (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:50:38.107 [DEBUG] DEBUG: [#<MCollective::RPC::Result:0x00000007d8b538 @agent="openshift", @action="cartridge_do", @results={:sender=>"ex-std-node1.int.rhcloud.com", :statuscode=>0, :statusmsg=>"OK", :data=>{:time=>nil, :output=>"", :exitcode=>0, :addtl_params=>nil}}>] (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:50:38.108 [DEBUG] DEBUG: MCollective Response Time (execute_direct: app-destroy): 4.36985879s  (Request ID: 7483f644611a17f6b7c39613a63ff51c) (pid:28209)
>2013-10-25 07:50:38.146 [DEBUG] Rollback ReserveGearUidOp (pid:28209)
>2013-10-25 07:50:38.196 [DEBUG] Rollback InitGearOp (pid:28209)
>2013-10-25 07:50:38.241 [ERROR] Reference ID: 7483f644611a17f6b7c39613a63ff51c - Node execution failure (invalid exit code from node).
>  /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.16.2/lib/openshift/mcollective_application_container_proxy.rb:2718:in `parse_result'
>  /opt/rh/ruby193/root/usr/share/gems/gems/openshift-origin-msg-broker-mcollective-1.16.2/lib/openshift/mcollective_application_container_proxy.rb:1738:in `update_cluster'
>  /opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/method_tracer.rb:518:in `block in update_cluster_with_trace_Custom_OpenShift_MCollectiveApplicationContainerProxy_update_cluster'
>  /opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/method_tracer.rb:268:in `trace_execution_scoped'
>  /opt/rh/ruby193/root/usr/share/gems/gems/newrelic_rpm-3.6.6.147/lib/new_relic/agent/method_tracer.rb:513:in `update_cluster_with_trace_Custom_OpenShift_MCollectiveApplicationContainerProxy_update_cluster'

Actions: View

Attachments on bug 1023520: 915793 | 915794 | 915795