builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-023 starttime: 1469251016.76 results: success (0) buildid: 20160722193055 builduid: 47e261a4d3684387bc1d630daa5f5478 revision: 0a819a2a98f684748b9fe59363ec3f994e16f4a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:56.765237) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:56.765735) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:56.779067) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:56.836501) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:56.837033) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-22 22:16:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 104M=0s 2016-07-22 22:16:57 (104 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.103239 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:56.977907) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:56.978280) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018197 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:57.031588) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:57.032127) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 0a819a2a98f684748b9fe59363ec3f994e16f4a0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 0a819a2a98f684748b9fe59363ec3f994e16f4a0 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-22 22:16:57,510 truncating revision to first 12 chars 2016-07-22 22:16:57,510 Setting DEBUG logging. 2016-07-22 22:16:57,510 attempt 1/10 2016-07-22 22:16:57,510 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/0a819a2a98f6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-22 22:16:57,944 unpacking tar archive at: mozilla-inbound-0a819a2a98f6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.764841 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:57.814473) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:57.814758) ========= script_repo_revision: 0a819a2a98f684748b9fe59363ec3f994e16f4a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:57.815519) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:57.815790) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-22 22:16:57.828506) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-22 22:16:57.828767) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:16:58 INFO - MultiFileLogger online at 20160722 22:16:58 in /builds/slave/test 22:16:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:16:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:16:58 INFO - {'append_to_log': False, 22:16:58 INFO - 'base_work_dir': '/builds/slave/test', 22:16:58 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:16:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:16:58 INFO - 'branch': 'Mozilla-Inbound', 22:16:58 INFO - 'buildbot_json_path': 'buildprops.json', 22:16:58 INFO - 'config_files': ('talos/linux_config.py',), 22:16:58 INFO - 'default_actions': ('clobber', 22:16:58 INFO - 'read-buildbot-config', 22:16:58 INFO - 'download-and-extract', 22:16:58 INFO - 'populate-webroot', 22:16:58 INFO - 'create-virtualenv', 22:16:58 INFO - 'install', 22:16:58 INFO - 'run-tests'), 22:16:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:16:58 INFO - 'download_minidump_stackwalk': True, 22:16:58 INFO - 'download_symbols': 'ondemand', 22:16:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:16:58 INFO - 'tooltool.py': '/tools/tooltool.py', 22:16:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:16:58 INFO - '/tools/misc-python/virtualenv.py')}, 22:16:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:16:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:16:58 INFO - 'installer_path': 'installer.exe', 22:16:58 INFO - 'log_level': 'info', 22:16:58 INFO - 'log_name': 'talos', 22:16:58 INFO - 'log_to_console': True, 22:16:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:16:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:16:58 INFO - 'opt_config_files': (), 22:16:58 INFO - 'pip_index': False, 22:16:58 INFO - 'sps_profile': False, 22:16:58 INFO - 'sps_profile_interval': 0, 22:16:58 INFO - 'suite': 'dromaeojs-e10s', 22:16:58 INFO - 'system_bits': '32', 22:16:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:16:58 INFO - 'title': 'talos-linux64-ix-023', 22:16:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:16:58 INFO - 'use_talos_json': True, 22:16:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:16:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:16:58 INFO - 'work_dir': 'build'} 22:16:58 INFO - [mozharness: 2016-07-23 05:16:58.401999Z] Running clobber step. 22:16:58 INFO - Running pre-action listener: _resource_record_pre_action 22:16:58 INFO - Running main action method: clobber 22:16:58 INFO - rmtree: /builds/slave/test/build 22:16:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:16:59 INFO - Running post-action listener: _resource_record_post_action 22:16:59 INFO - [mozharness: 2016-07-23 05:16:59.393184Z] Finished clobber step (success) 22:16:59 INFO - [mozharness: 2016-07-23 05:16:59.393285Z] Running read-buildbot-config step. 22:16:59 INFO - Running pre-action listener: _resource_record_pre_action 22:16:59 INFO - Running main action method: read_buildbot_config 22:16:59 INFO - Using buildbot properties: 22:16:59 INFO - { 22:16:59 INFO - "project": "", 22:16:59 INFO - "product": "firefox", 22:16:59 INFO - "script_repo_revision": "production", 22:16:59 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 22:16:59 INFO - "repository": "", 22:16:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 22:16:59 INFO - "buildid": "20160722193055", 22:16:59 INFO - "pgo_build": "True", 22:16:59 INFO - "basedir": "/builds/slave/test", 22:16:59 INFO - "buildnumber": 1340, 22:16:59 INFO - "slavename": "talos-linux64-ix-023", 22:16:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:16:59 INFO - "platform": "ubuntu64_hw", 22:16:59 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:16:59 INFO - "branch": "mozilla-inbound", 22:16:59 INFO - "repo_path": "integration/mozilla-inbound", 22:16:59 INFO - "revision": "0a819a2a98f684748b9fe59363ec3f994e16f4a0", 22:16:59 INFO - "stage_platform": "linux64-pgo", 22:16:59 INFO - "builduid": "47e261a4d3684387bc1d630daa5f5478", 22:16:59 INFO - "slavebuilddir": "test" 22:16:59 INFO - } 22:16:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 22:16:59 INFO - Running post-action listener: _resource_record_post_action 22:16:59 INFO - [mozharness: 2016-07-23 05:16:59.395749Z] Finished read-buildbot-config step (success) 22:16:59 INFO - [mozharness: 2016-07-23 05:16:59.395837Z] Running download-and-extract step. 22:16:59 INFO - Running pre-action listener: _resource_record_pre_action 22:16:59 INFO - Running main action method: download_and_extract 22:16:59 INFO - mkdir: /builds/slave/test/build/tests 22:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:16:59 INFO - https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:16:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:16:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:16:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:16:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:17:06 INFO - Downloaded 1361 bytes. 22:17:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 22:17:06 INFO - Using the following test package requirements: 22:17:06 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:17:06 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:17:06 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:17:06 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'jsshell-linux-x86_64.zip'], 22:17:06 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:17:06 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 22:17:06 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:17:06 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:17:06 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:17:06 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 22:17:06 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:17:06 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:06 INFO - https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:17:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:17:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:17:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:17:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:17:09 INFO - Downloaded 24312199 bytes. 22:17:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:17:10 INFO - Return code: 0 22:17:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:10 INFO - https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:17:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:17:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:17:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 22:17:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:17:20 INFO - Downloaded 24312199 bytes. 22:17:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:17:21 INFO - Return code: 0 22:17:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:21 INFO - https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:17:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:17:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:17:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 22:17:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:17:22 INFO - Downloaded 14403289 bytes. 22:17:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:17:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:17:23 INFO - Return code: 0 22:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:23 INFO - https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:17:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:17:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:17:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:17:24 INFO - Downloaded 60861198 bytes. 22:17:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:17:24 INFO - mkdir: /builds/slave/test/properties 22:17:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:17:24 INFO - Writing to file /builds/slave/test/properties/build_url 22:17:24 INFO - Contents: 22:17:24 INFO - build_url:https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 22:17:25 INFO - Running post-action listener: _resource_record_post_action 22:17:25 INFO - Running post-action listener: set_extra_try_arguments 22:17:25 INFO - [mozharness: 2016-07-23 05:17:25.167809Z] Finished download-and-extract step (success) 22:17:25 INFO - [mozharness: 2016-07-23 05:17:25.168046Z] Running populate-webroot step. 22:17:25 INFO - Running pre-action listener: _resource_record_pre_action 22:17:25 INFO - Running main action method: populate_webroot 22:17:25 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:17:25 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:17:25 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:17:25 INFO - '--tppagecycles', 22:17:25 INFO - '7'], 22:17:25 INFO - 'tests': ['tsvgm']}}, 22:17:25 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:17:25 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:17:25 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:17:25 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:17:25 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:17:25 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:17:25 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:25 INFO - 'talos_options': ['--disable-e10s'], 22:17:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:25 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:25 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:17:25 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:25 INFO - 'talos_options': ['--disable-e10s'], 22:17:25 INFO - 'tests': ['damp', 'tps']}, 22:17:25 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:25 INFO - 'tests': ['damp', 'tps']}, 22:17:25 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:17:25 INFO - 'tests': ['dromaeo_dom']}, 22:17:25 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:17:25 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:17:25 INFO - 'tests': ['basic_compositor_video']}, 22:17:25 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:17:25 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:17:25 INFO - 'tests': ['a11yr', 22:17:25 INFO - 'ts_paint', 22:17:25 INFO - 'tpaint', 22:17:25 INFO - 'sessionrestore', 22:17:25 INFO - 'sessionrestore_no_auto_restore', 22:17:25 INFO - 'tabpaint']}, 22:17:25 INFO - 'other-e10s': {'tests': ['a11yr', 22:17:25 INFO - 'ts_paint', 22:17:25 INFO - 'tpaint', 22:17:25 INFO - 'sessionrestore', 22:17:25 INFO - 'sessionrestore_no_auto_restore', 22:17:25 INFO - 'tabpaint']}, 22:17:25 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:17:25 INFO - 'ts_paint', 22:17:25 INFO - 'tpaint', 22:17:25 INFO - 'sessionrestore', 22:17:25 INFO - 'sessionrestore_no_auto_restore', 22:17:25 INFO - 'tabpaint']}, 22:17:25 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:17:25 INFO - 'ts_paint', 22:17:25 INFO - 'tpaint', 22:17:25 INFO - 'sessionrestore', 22:17:25 INFO - 'sessionrestore_no_auto_restore', 22:17:25 INFO - 'tabpaint']}, 22:17:25 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:17:25 INFO - 'tests': ['a11yr', 22:17:25 INFO - 'ts_paint', 22:17:25 INFO - 'tpaint', 22:17:25 INFO - 'sessionrestore', 22:17:25 INFO - 'sessionrestore_no_auto_restore', 22:17:25 INFO - 'tabpaint']}, 22:17:25 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:17:25 INFO - 'tests': ['a11yr', 22:17:25 INFO - 'ts_paint', 22:17:25 INFO - 'tpaint', 22:17:25 INFO - 'sessionrestore', 22:17:25 INFO - 'sessionrestore_no_auto_restore', 22:17:25 INFO - 'tabpaint']}, 22:17:25 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:17:25 INFO - 'tests': ['tsvgx', 22:17:25 INFO - 'tsvgr_opacity', 22:17:25 INFO - 'tart', 22:17:25 INFO - 'tscrollx', 22:17:25 INFO - 'cart']}, 22:17:25 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:17:25 INFO - 'tsvgr_opacity', 22:17:25 INFO - 'tart', 22:17:25 INFO - 'tscrollx', 22:17:25 INFO - 'cart']}, 22:17:25 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:25 INFO - 'talos_options': ['--disable-e10s'], 22:17:25 INFO - 'tests': ['tp5o']}, 22:17:25 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:25 INFO - 'tests': ['tp5o']}, 22:17:25 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:25 INFO - 'talos_options': ['--disable-e10s', 22:17:25 INFO - '--xperf_path', 22:17:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:25 INFO - 'tests': ['tp5n']}, 22:17:25 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:17:25 INFO - 'talos_options': ['--xperf_path', 22:17:25 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:17:25 INFO - 'tests': ['tp5n']}}, 22:17:25 INFO - 'talos.zip': {'path': '', 22:17:25 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:17:25 INFO - Running post-action listener: _resource_record_post_action 22:17:25 INFO - [mozharness: 2016-07-23 05:17:25.189910Z] Finished populate-webroot step (success) 22:17:25 INFO - [mozharness: 2016-07-23 05:17:25.189999Z] Running create-virtualenv step. 22:17:25 INFO - Running pre-action listener: _resource_record_pre_action 22:17:25 INFO - Running main action method: create_virtualenv 22:17:25 INFO - Creating virtualenv /builds/slave/test/build/venv 22:17:25 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:17:25 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:17:25 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:17:25 INFO - Using real prefix '/usr' 22:17:25 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:17:25 INFO - Installing distribute.............................................................................................................................................................................................done. 22:17:27 INFO - Installing pip.................done. 22:17:27 INFO - Return code: 0 22:17:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e47470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e47900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:17:27 INFO - Using env: {'DISPLAY': ':0', 22:17:27 INFO - 'HOME': '/home/cltbld', 22:17:27 INFO - 'LANG': 'en_US.UTF-8', 22:17:27 INFO - 'LANGUAGE': 'en_US:en', 22:17:27 INFO - 'LOGNAME': 'cltbld', 22:17:27 INFO - 'MAIL': '/var/mail/cltbld', 22:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:27 INFO - 'MOZ_NO_REMOTE': '1', 22:17:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:27 INFO - 'NO_EM_RESTART': '1', 22:17:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:27 INFO - 'PWD': '/builds/slave/test', 22:17:27 INFO - 'SHELL': '/bin/bash', 22:17:27 INFO - 'SHLVL': '1', 22:17:27 INFO - 'TERM': 'linux', 22:17:27 INFO - 'TMOUT': '86400', 22:17:27 INFO - 'USER': 'cltbld', 22:17:27 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 22:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:27 INFO - '_': '/tools/buildbot/bin/python'} 22:17:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:27 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:17:27 INFO - Cleaning up... 22:17:27 INFO - Return code: 0 22:17:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e47470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e47900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:17:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:17:27 INFO - Using env: {'DISPLAY': ':0', 22:17:27 INFO - 'HOME': '/home/cltbld', 22:17:27 INFO - 'LANG': 'en_US.UTF-8', 22:17:27 INFO - 'LANGUAGE': 'en_US:en', 22:17:27 INFO - 'LOGNAME': 'cltbld', 22:17:27 INFO - 'MAIL': '/var/mail/cltbld', 22:17:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:27 INFO - 'MOZ_NO_REMOTE': '1', 22:17:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:27 INFO - 'NO_EM_RESTART': '1', 22:17:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:27 INFO - 'PWD': '/builds/slave/test', 22:17:27 INFO - 'SHELL': '/bin/bash', 22:17:27 INFO - 'SHLVL': '1', 22:17:27 INFO - 'TERM': 'linux', 22:17:27 INFO - 'TMOUT': '86400', 22:17:27 INFO - 'USER': 'cltbld', 22:17:27 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 22:17:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:27 INFO - '_': '/tools/buildbot/bin/python'} 22:17:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:28 INFO - Downloading/unpacking psutil>=3.1.1 22:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:17:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:30 INFO - Installing collected packages: psutil 22:17:30 INFO - Running setup.py install for psutil 22:17:30 INFO - building 'psutil._psutil_linux' extension 22:17:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:17:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:17:30 INFO - building 'psutil._psutil_posix' extension 22:17:30 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:17:30 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:17:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:17:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:17:30 INFO - Successfully installed psutil 22:17:30 INFO - Cleaning up... 22:17:30 INFO - Return code: 0 22:17:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e47470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e47900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:17:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:17:30 INFO - Using env: {'DISPLAY': ':0', 22:17:30 INFO - 'HOME': '/home/cltbld', 22:17:30 INFO - 'LANG': 'en_US.UTF-8', 22:17:30 INFO - 'LANGUAGE': 'en_US:en', 22:17:30 INFO - 'LOGNAME': 'cltbld', 22:17:30 INFO - 'MAIL': '/var/mail/cltbld', 22:17:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:30 INFO - 'MOZ_NO_REMOTE': '1', 22:17:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:30 INFO - 'NO_EM_RESTART': '1', 22:17:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:30 INFO - 'PWD': '/builds/slave/test', 22:17:30 INFO - 'SHELL': '/bin/bash', 22:17:30 INFO - 'SHLVL': '1', 22:17:30 INFO - 'TERM': 'linux', 22:17:30 INFO - 'TMOUT': '86400', 22:17:30 INFO - 'USER': 'cltbld', 22:17:30 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 22:17:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:30 INFO - '_': '/tools/buildbot/bin/python'} 22:17:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:17:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:17:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:17:32 INFO - Installing collected packages: mozsystemmonitor 22:17:32 INFO - Running setup.py install for mozsystemmonitor 22:17:32 INFO - Successfully installed mozsystemmonitor 22:17:32 INFO - Cleaning up... 22:17:33 INFO - Return code: 0 22:17:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e47470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e47900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:17:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:17:33 INFO - Using env: {'DISPLAY': ':0', 22:17:33 INFO - 'HOME': '/home/cltbld', 22:17:33 INFO - 'LANG': 'en_US.UTF-8', 22:17:33 INFO - 'LANGUAGE': 'en_US:en', 22:17:33 INFO - 'LOGNAME': 'cltbld', 22:17:33 INFO - 'MAIL': '/var/mail/cltbld', 22:17:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:33 INFO - 'MOZ_NO_REMOTE': '1', 22:17:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:33 INFO - 'NO_EM_RESTART': '1', 22:17:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:33 INFO - 'PWD': '/builds/slave/test', 22:17:33 INFO - 'SHELL': '/bin/bash', 22:17:33 INFO - 'SHLVL': '1', 22:17:33 INFO - 'TERM': 'linux', 22:17:33 INFO - 'TMOUT': '86400', 22:17:33 INFO - 'USER': 'cltbld', 22:17:33 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 22:17:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:33 INFO - '_': '/tools/buildbot/bin/python'} 22:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:33 INFO - Downloading/unpacking jsonschema==2.5.1 22:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:17:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:35 INFO - Downloading functools32-3.2.3-2.tar.gz 22:17:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:17:35 INFO - warning: no files found matching '*.txt' 22:17:35 INFO - no previously-included directories found matching 'build' 22:17:35 INFO - no previously-included directories found matching 'dist' 22:17:35 INFO - no previously-included directories found matching '.git*' 22:17:35 INFO - Installing collected packages: jsonschema, functools32 22:17:35 INFO - Running setup.py install for functools32 22:17:35 INFO - warning: no files found matching '*.txt' 22:17:35 INFO - no previously-included directories found matching 'build' 22:17:35 INFO - no previously-included directories found matching 'dist' 22:17:35 INFO - no previously-included directories found matching '.git*' 22:17:35 INFO - Successfully installed jsonschema functools32 22:17:35 INFO - Cleaning up... 22:17:35 INFO - Return code: 0 22:17:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e47470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e47900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:17:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:17:35 INFO - Using env: {'DISPLAY': ':0', 22:17:35 INFO - 'HOME': '/home/cltbld', 22:17:35 INFO - 'LANG': 'en_US.UTF-8', 22:17:35 INFO - 'LANGUAGE': 'en_US:en', 22:17:35 INFO - 'LOGNAME': 'cltbld', 22:17:35 INFO - 'MAIL': '/var/mail/cltbld', 22:17:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:35 INFO - 'MOZ_NO_REMOTE': '1', 22:17:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:35 INFO - 'NO_EM_RESTART': '1', 22:17:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:35 INFO - 'PWD': '/builds/slave/test', 22:17:35 INFO - 'SHELL': '/bin/bash', 22:17:35 INFO - 'SHLVL': '1', 22:17:35 INFO - 'TERM': 'linux', 22:17:35 INFO - 'TMOUT': '86400', 22:17:35 INFO - 'USER': 'cltbld', 22:17:35 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 22:17:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:35 INFO - '_': '/tools/buildbot/bin/python'} 22:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:35 INFO - Downloading/unpacking blobuploader==1.2.4 22:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:37 INFO - Downloading blobuploader-1.2.4.tar.gz 22:17:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:17:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:17:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:38 INFO - Downloading docopt-0.6.1.tar.gz 22:17:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:17:38 INFO - Installing collected packages: blobuploader, requests, docopt 22:17:38 INFO - Running setup.py install for blobuploader 22:17:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:17:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:17:38 INFO - Running setup.py install for requests 22:17:38 INFO - Running setup.py install for docopt 22:17:39 INFO - Successfully installed blobuploader requests docopt 22:17:39 INFO - Cleaning up... 22:17:39 INFO - Return code: 0 22:17:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e47470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e47900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:17:39 INFO - Using env: {'DISPLAY': ':0', 22:17:39 INFO - 'HOME': '/home/cltbld', 22:17:39 INFO - 'LANG': 'en_US.UTF-8', 22:17:39 INFO - 'LANGUAGE': 'en_US:en', 22:17:39 INFO - 'LOGNAME': 'cltbld', 22:17:39 INFO - 'MAIL': '/var/mail/cltbld', 22:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:39 INFO - 'MOZ_NO_REMOTE': '1', 22:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:39 INFO - 'NO_EM_RESTART': '1', 22:17:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:39 INFO - 'PWD': '/builds/slave/test', 22:17:39 INFO - 'SHELL': '/bin/bash', 22:17:39 INFO - 'SHLVL': '1', 22:17:39 INFO - 'TERM': 'linux', 22:17:39 INFO - 'TMOUT': '86400', 22:17:39 INFO - 'USER': 'cltbld', 22:17:39 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 22:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:39 INFO - '_': '/tools/buildbot/bin/python'} 22:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:39 INFO - Running setup.py (path:/tmp/pip-Np3Utq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:39 INFO - Running setup.py (path:/tmp/pip-c0tWTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:39 INFO - Running setup.py (path:/tmp/pip-vf2Pbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:39 INFO - Running setup.py (path:/tmp/pip-Tb8sok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:39 INFO - Running setup.py (path:/tmp/pip-ydTXZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:39 INFO - Running setup.py (path:/tmp/pip-Qe4Atp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:39 INFO - Running setup.py (path:/tmp/pip-06Kyff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:39 INFO - Running setup.py (path:/tmp/pip-6crfdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:39 INFO - Running setup.py (path:/tmp/pip-Ee_6Kn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:39 INFO - Running setup.py (path:/tmp/pip-RjGhJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:40 INFO - Running setup.py (path:/tmp/pip-IFWtnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:40 INFO - Running setup.py (path:/tmp/pip-5LXcbX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:40 INFO - Running setup.py (path:/tmp/pip-OCR6PG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:40 INFO - Running setup.py (path:/tmp/pip-R9FR92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:40 INFO - Running setup.py (path:/tmp/pip-zKW8yU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:40 INFO - Running setup.py (path:/tmp/pip-_43pP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:40 INFO - Running setup.py (path:/tmp/pip-1AUpGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:17:40 INFO - Running setup.py install for manifestparser 22:17:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Running setup.py install for mozcrash 22:17:40 INFO - Running setup.py install for mozdebug 22:17:40 INFO - Running setup.py install for mozdevice 22:17:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:17:40 INFO - Running setup.py install for mozfile 22:17:41 INFO - Running setup.py install for mozhttpd 22:17:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Running setup.py install for mozinfo 22:17:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Running setup.py install for mozInstall 22:17:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Running setup.py install for mozleak 22:17:41 INFO - Running setup.py install for mozlog 22:17:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Running setup.py install for moznetwork 22:17:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Running setup.py install for mozprocess 22:17:41 INFO - Running setup.py install for mozprofile 22:17:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:17:41 INFO - Running setup.py install for mozrunner 22:17:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:17:42 INFO - Running setup.py install for mozscreenshot 22:17:42 INFO - Running setup.py install for moztest 22:17:42 INFO - Running setup.py install for mozversion 22:17:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:17:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:17:42 INFO - Cleaning up... 22:17:42 INFO - Return code: 0 22:17:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e47470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e47900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:17:42 INFO - Using env: {'DISPLAY': ':0', 22:17:42 INFO - 'HOME': '/home/cltbld', 22:17:42 INFO - 'LANG': 'en_US.UTF-8', 22:17:42 INFO - 'LANGUAGE': 'en_US:en', 22:17:42 INFO - 'LOGNAME': 'cltbld', 22:17:42 INFO - 'MAIL': '/var/mail/cltbld', 22:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:42 INFO - 'MOZ_NO_REMOTE': '1', 22:17:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:42 INFO - 'NO_EM_RESTART': '1', 22:17:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:42 INFO - 'PWD': '/builds/slave/test', 22:17:42 INFO - 'SHELL': '/bin/bash', 22:17:42 INFO - 'SHLVL': '1', 22:17:42 INFO - 'TERM': 'linux', 22:17:42 INFO - 'TMOUT': '86400', 22:17:42 INFO - 'USER': 'cltbld', 22:17:42 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 22:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:42 INFO - '_': '/tools/buildbot/bin/python'} 22:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:17:42 INFO - Running setup.py (path:/tmp/pip-ts9lim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:17:42 INFO - Running setup.py (path:/tmp/pip-3iucS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:17:42 INFO - Running setup.py (path:/tmp/pip-iyRrF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:17:42 INFO - Running setup.py (path:/tmp/pip-36G7YU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:17:42 INFO - Running setup.py (path:/tmp/pip-me3EMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:17:42 INFO - Running setup.py (path:/tmp/pip-DUKEyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:17:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:17:43 INFO - Running setup.py (path:/tmp/pip-VS7tjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:17:43 INFO - Running setup.py (path:/tmp/pip-Ubxhms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:17:43 INFO - Running setup.py (path:/tmp/pip-c2WraT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:17:43 INFO - Running setup.py (path:/tmp/pip-ENASCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:17:43 INFO - Running setup.py (path:/tmp/pip-uNFz3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:17:43 INFO - Running setup.py (path:/tmp/pip-1sM_F9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:17:43 INFO - Running setup.py (path:/tmp/pip-wrwNJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:17:43 INFO - Running setup.py (path:/tmp/pip-DzNypq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:43 INFO - Running setup.py (path:/tmp/pip-iQF4Ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:17:43 INFO - Running setup.py (path:/tmp/pip-gHpGer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:17:43 INFO - Running setup.py (path:/tmp/pip-DVcTbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:17:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:17:45 INFO - Downloading blessings-1.6.tar.gz 22:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:17:45 INFO - Installing collected packages: blessings 22:17:45 INFO - Running setup.py install for blessings 22:17:45 INFO - Successfully installed blessings 22:17:45 INFO - Cleaning up... 22:17:45 INFO - Return code: 0 22:17:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:17:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:46 INFO - Reading from file tmpfile_stdout 22:17:46 INFO - Current package versions: 22:17:46 INFO - argparse == 1.2.1 22:17:46 INFO - blessings == 1.6 22:17:46 INFO - blobuploader == 1.2.4 22:17:46 INFO - docopt == 0.6.1 22:17:46 INFO - functools32 == 3.2.3-2 22:17:46 INFO - jsonschema == 2.5.1 22:17:46 INFO - manifestparser == 1.1 22:17:46 INFO - mozInstall == 1.12 22:17:46 INFO - mozcrash == 0.17 22:17:46 INFO - mozdebug == 0.1 22:17:46 INFO - mozdevice == 0.48 22:17:46 INFO - mozfile == 1.2 22:17:46 INFO - mozhttpd == 0.7 22:17:46 INFO - mozinfo == 0.9 22:17:46 INFO - mozleak == 0.1 22:17:46 INFO - mozlog == 3.3 22:17:46 INFO - moznetwork == 0.27 22:17:46 INFO - mozprocess == 0.23 22:17:46 INFO - mozprofile == 0.28 22:17:46 INFO - mozrunner == 6.12 22:17:46 INFO - mozscreenshot == 0.1 22:17:46 INFO - mozsystemmonitor == 0.3 22:17:46 INFO - moztest == 0.7 22:17:46 INFO - mozversion == 1.4 22:17:46 INFO - psutil == 3.1.1 22:17:46 INFO - requests == 1.2.3 22:17:46 INFO - wsgiref == 0.1.2 22:17:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e47470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e47900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:17:46 INFO - Using env: {'DISPLAY': ':0', 22:17:46 INFO - 'HOME': '/home/cltbld', 22:17:46 INFO - 'LANG': 'en_US.UTF-8', 22:17:46 INFO - 'LANGUAGE': 'en_US:en', 22:17:46 INFO - 'LOGNAME': 'cltbld', 22:17:46 INFO - 'MAIL': '/var/mail/cltbld', 22:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:46 INFO - 'MOZ_NO_REMOTE': '1', 22:17:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:46 INFO - 'NO_EM_RESTART': '1', 22:17:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:46 INFO - 'PWD': '/builds/slave/test', 22:17:46 INFO - 'SHELL': '/bin/bash', 22:17:46 INFO - 'SHLVL': '1', 22:17:46 INFO - 'TERM': 'linux', 22:17:46 INFO - 'TMOUT': '86400', 22:17:46 INFO - 'USER': 'cltbld', 22:17:46 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 22:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:46 INFO - '_': '/tools/buildbot/bin/python'} 22:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:17:46 INFO - Cleaning up... 22:17:46 INFO - Return code: 0 22:17:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:17:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b88030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ceb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1db7400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e47470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e47900>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:17:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:17:46 INFO - Using env: {'DISPLAY': ':0', 22:17:46 INFO - 'HOME': '/home/cltbld', 22:17:46 INFO - 'LANG': 'en_US.UTF-8', 22:17:46 INFO - 'LANGUAGE': 'en_US:en', 22:17:46 INFO - 'LOGNAME': 'cltbld', 22:17:46 INFO - 'MAIL': '/var/mail/cltbld', 22:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:17:46 INFO - 'MOZ_NO_REMOTE': '1', 22:17:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:17:46 INFO - 'NO_EM_RESTART': '1', 22:17:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:17:46 INFO - 'PWD': '/builds/slave/test', 22:17:46 INFO - 'SHELL': '/bin/bash', 22:17:46 INFO - 'SHLVL': '1', 22:17:46 INFO - 'TERM': 'linux', 22:17:46 INFO - 'TMOUT': '86400', 22:17:46 INFO - 'USER': 'cltbld', 22:17:46 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 22:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:17:46 INFO - '_': '/tools/buildbot/bin/python'} 22:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:17:46 INFO - Cleaning up... 22:17:46 INFO - Return code: 0 22:17:46 INFO - Running post-action listener: _resource_record_post_action 22:17:46 INFO - Running post-action listener: _start_resource_monitoring 22:17:46 INFO - Starting resource monitoring. 22:17:46 INFO - [mozharness: 2016-07-23 05:17:46.649479Z] Finished create-virtualenv step (success) 22:17:46 INFO - [mozharness: 2016-07-23 05:17:46.649923Z] Running install step. 22:17:46 INFO - Running pre-action listener: _resource_record_pre_action 22:17:46 INFO - Running main action method: install 22:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:17:46 INFO - Reading from file tmpfile_stdout 22:17:46 INFO - Detecting whether we're running mozinstall >=1.0... 22:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:17:46 INFO - Reading from file tmpfile_stdout 22:17:46 INFO - Output received: 22:17:46 INFO - Usage: mozinstall [options] installer 22:17:46 INFO - Options: 22:17:46 INFO - -h, --help show this help message and exit 22:17:46 INFO - -d DEST, --destination=DEST 22:17:46 INFO - Directory to install application into. [default: 22:17:46 INFO - "/builds/slave/test"] 22:17:46 INFO - --app=APP Application being installed. [default: firefox] 22:17:46 INFO - mkdir: /builds/slave/test/build/application 22:17:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:18:04 INFO - Reading from file tmpfile_stdout 22:18:04 INFO - Output received: 22:18:04 INFO - /builds/slave/test/build/application/firefox/firefox 22:18:04 INFO - Running post-action listener: _resource_record_post_action 22:18:04 INFO - [mozharness: 2016-07-23 05:18:04.183857Z] Finished install step (success) 22:18:04 INFO - [mozharness: 2016-07-23 05:18:04.184140Z] Running run-tests step. 22:18:04 INFO - Running pre-action listener: _resource_record_pre_action 22:18:04 INFO - Running main action method: run_tests 22:18:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:18:04 INFO - Python 2.7.3 22:18:04 INFO - Return code: 0 22:18:04 INFO - grabbing minidump binary from tooltool 22:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:18:04 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e46570>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e47470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e47900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:18:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:18:04 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:18:04 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 22:18:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:18:04 INFO - Return code: 0 22:18:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:18:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:18:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:18:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:18:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:18:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 22:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 22:18:04 INFO - Using env: {'DISPLAY': ':0', 22:18:04 INFO - 'HOME': '/home/cltbld', 22:18:04 INFO - 'LANG': 'en_US.UTF-8', 22:18:04 INFO - 'LANGUAGE': 'en_US:en', 22:18:04 INFO - 'LOGNAME': 'cltbld', 22:18:04 INFO - 'MAIL': '/var/mail/cltbld', 22:18:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:18:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:18:04 INFO - 'MOZ_NO_REMOTE': '1', 22:18:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:18:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:18:04 INFO - 'NO_EM_RESTART': '1', 22:18:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:18:04 INFO - 'PWD': '/builds/slave/test', 22:18:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:18:04 INFO - 'SHELL': '/bin/bash', 22:18:04 INFO - 'SHLVL': '1', 22:18:04 INFO - 'TERM': 'linux', 22:18:04 INFO - 'TMOUT': '86400', 22:18:04 INFO - 'USER': 'cltbld', 22:18:04 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161', 22:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:18:04 INFO - '_': '/tools/buildbot/bin/python'} 22:18:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 22:18:04 INFO - mozversion application_buildid: 20160722193055 22:18:04 INFO - mozversion application_changeset: 0a819a2a98f684748b9fe59363ec3f994e16f4a0 22:18:04 INFO - mozversion application_display_name: Nightly 22:18:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:18:04 INFO - mozversion application_name: Firefox 22:18:04 INFO - mozversion application_remotingname: firefox 22:18:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:18:04 INFO - mozversion application_vendor: Mozilla 22:18:04 INFO - mozversion application_version: 50.0a1 22:18:04 INFO - mozversion platform_buildid: 20160722193055 22:18:04 INFO - mozversion platform_changeset: 0a819a2a98f684748b9fe59363ec3f994e16f4a0 22:18:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:18:04 INFO - mozversion platform_version: 50.0a1 22:18:04 INFO - using testdate: 1469251084 22:18:04 INFO - actual date: 1469251084 22:18:04 INFO - starting webserver on 'localhost:53275' 22:18:04 INFO - SUITE-START | Running 2 tests 22:18:04 INFO - TEST-START | dromaeo_css 22:18:04 INFO - operating with platform_type : linux_ 22:18:04 INFO - Initialising browser for dromaeo_css test... 22:18:04 INFO - TEST-INFO | started process 20401 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGXFQMh/profile http://localhost:53275/getInfo.html) 22:18:07 INFO - PROCESS | 20401 | JavaScript error: , line 0: uncaught exception: undefined 22:18:09 INFO - PROCESS | 20401 | __metrics Screen width/height:1600/1200 22:18:09 INFO - PROCESS | 20401 | colorDepth:24 22:18:09 INFO - PROCESS | 20401 | Browser inner width/height: 1024/697 22:18:09 INFO - PROCESS | 20401 | __metrics 22:18:09 ERROR - PROCESS | 20401 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 22:18:10 INFO - TEST-INFO | 20401: exit 0 22:18:10 INFO - Browser initialized. 22:18:10 INFO - Running cycle 1/1 for dromaeo_css test... 22:18:10 INFO - TEST-INFO | started process 20523 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGXFQMh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:18:22 INFO - PROCESS | 20523 | RSS: Main: 168038400 22:18:22 INFO - PROCESS | 20523 | 22:19:12 INFO - PROCESS | 20523 | 1469251152031 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:21:20 INFO - PROCESS | 20523 | Cycle 1(1): loaded http://localhost:53275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53275/tests/dromaeo/cssquery-ext.html) 22:21:21 INFO - PROCESS | 20523 | RSS: Main: 172015616 22:21:21 INFO - PROCESS | 20523 | 22:24:23 INFO - PROCESS | 20523 | Cycle 1(1): loaded http://localhost:53275/tests/dromaeo/cssquery-ext.html (next: http://localhost:53275/tests/dromaeo/cssquery-jquery.html) 22:24:24 INFO - PROCESS | 20523 | RSS: Main: 170151936 22:24:24 INFO - PROCESS | 20523 | 22:27:26 INFO - PROCESS | 20523 | Cycle 1(1): loaded http://localhost:53275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53275/tests/dromaeo/cssquery-mootools.html) 22:27:26 INFO - PROCESS | 20523 | RSS: Main: 173187072 22:27:26 INFO - PROCESS | 20523 | 22:30:28 INFO - PROCESS | 20523 | Cycle 1(1): loaded http://localhost:53275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53275/tests/dromaeo/cssquery-prototype.html) 22:30:29 INFO - PROCESS | 20523 | RSS: Main: 168509440 22:30:29 INFO - PROCESS | 20523 | 22:33:31 INFO - PROCESS | 20523 | Cycle 1(1): loaded http://localhost:53275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53275/tests/dromaeo/cssquery-yui.html) 22:33:32 INFO - PROCESS | 20523 | RSS: Main: 170151936 22:33:32 INFO - PROCESS | 20523 | 22:36:35 INFO - PROCESS | 20523 | Cycle 1(1): loaded http://localhost:53275/tests/dromaeo/cssquery-yui.html (next: http://localhost:53275/tests/dromaeo/cssquery-dojo.html) 22:36:35 INFO - PROCESS | 20523 | RSS: Main: 173039616 22:36:35 INFO - PROCESS | 20523 | 22:36:35 INFO - PROCESS | 20523 | __start_tp_report 22:36:35 INFO - PROCESS | 20523 | _x_x_mozilla_page_load 22:36:35 INFO - PROCESS | 20523 | _x_x_mozilla_page_load_details 22:36:35 INFO - PROCESS | 20523 | |i|pagename|runs| 22:36:35 INFO - PROCESS | 20523 | |0;dojo.html;3083.136721717919;3084.2135255509843;3105.596272484577;3116.2365220520974;3118.968810311896;5421.945780542198;5500.202470641754;5501.862453438666;5503.944960550393;5523.696196709179;5414.810481633143;5423.376311724153;5448.8365349039495;5495.807646732355;5555.2222688823595;4935.55579997801;4986.40163180417;5098.872528186806;5131.358580177478;5139.974300128512;5011.2483127530795;5156.69059856408;5157.871053223662;5167.302414174094;5168.870778230536;4932.136876046678;4933.1860243059855;5030.622703297267;5050;5114.514121158484;4915.115279249733;4932.901341973176;4940.802367905279;4951;5070.847874563732;4943.9258411123865;4954.43024052235;4992;4997.200447928314;5038.697678139323;5355.866103347408;5432.70120143392;5472.179173124024;5481.561475081984;5527.198556209368;5861.736221870026;5862.912056319158;5864.1203819427;5869.295684517842;5906.8818623627285;5862.648241105548;5906;5910.320313163955;5910.497607703351;5910.936031514326;5370.194470829368;5466.070767969455;5494.835154945358;5522.889542209134;5547.029269877755;6064.605800622945;6071.544634152457;6073;6081.118237855575;6107.206063211843;5802.448767367177;5824.3302020267365;5825.38833422493;5849.444302791228;5869.8239052828485;5807.390224026416;5882.470577648032;5919.052951527735;5923.437273459014;5927.673977931169;5682.7442765075675;5860.208871802337;5884.911726324109;5911.261092363455;5971.790987315423;5906.793262235887;5916.763329466859;5957.791477298274;5975.970120149372;6012.7294271757;4413.691041627056;4441.067375851043;4462.821487140543;4512.954870451254;4521.0279789845345;3941.0541470047556;3987.222491614107;4010.8997275068355;4021.9195616087513;4027.033511957168;5539.390667026624;5615.971920140373;5654.943450565524;5656.660600363992;5664.68844213564;5730.856728581819;5900.64596124234;5924;5937.762489500372;5938.198343223696;5642.774289028475;5724.055530837449;5737.90979713853;5748.683822389725;5764.682942438206;5151.356080489939;5157.6647517911215;5163.870903227375;5164.586833053347;5172.353455818023;5946.583739138306;5952.791652292149;6148.9692551537855;6167.321594624587;6170.043643235305;5855.473007429352;5939.07944268639;5959.821205363846;5991;6005.939940600538;5989.760409583742;6038.275406951194;6068.7269072892705;6071.058985857199;6087.482563982111;5636.605437619327;5731.226284451713;5798.797042103506;5818.592698511064;5820;5870.853228669317;5938.465538101592;5943.257092863392;5958.842321935;6008;5700.885982280414;5770.307563092456;5782.7686892523825;5786.479216870502;5786.9131963019745;5664.603477756517;5674;5742.655440673573;5774.682392468455;5779.913301300568;5646.463585959327;5692.7722891083895;5700.771969121189;5728.312602487728;5741.311042674905;5767.7404516796005;5787.681677507778;5796.246487956707;5825.271841019872;5827.067669172912;5535.723213839211;5604.8038318658655;5634.380218175916;5638.23883775685;5706.343770466449 22:36:35 INFO - PROCESS | 20523 | |1;ext.html;540.2708884350029;581.2066529187657;590.8700085981084;602.6534742523049;622.1072760588559;39029.609703902985;43015;43031.35452968204;43089.13821723564;43215;43373.13253734924;43507.56492435075;43766;43797.7810110949;43922.560774392325;8662.047174810781;8849.734507964771;8850.159234872677;8853.335999800009;8853.95573022134;8602.18279263469;8726.258268047224;8746.125387461234;8751.912480875206;8794.956025219897;8743;8803.77990550235;8807;8821.11788821116;8823.161799629024;8777.824443511126;8783.077776833405;8815.647374105029;8816.603252853636;8856.911430885737;8721.12788721129;8731.126887311282;8773.517456539888;8774.780630484289;8777.7805554861;40638.59361406378;41018.38472422886;41133.38299925504;41254.1749165015;41338.79330603328;3617.963820361815;3704.518412606344;3762.9097440503137;3766.322062028834;4120.134771697947;32645.878017928997;32760.508592371134;32869.67130328714;32871;32947.50578741321;4326.134773045404;4423.867283981485;4462.553744625511;4468.575485328888;4599.83900563482;335314;336605;339172;339177;341495;57795.422045779844;58233.84726138344;58302.7084864573;58302.70848645814;58391.70804146037;60759.35443873382;61138;61580.07629885555;61775.38224617786;61867;15063.397464101314;15065.698686026437;15262.694746105015;15266.923665381602;15296.235188240544;12378;12417.565385211474;12489.375531223403;12512.436940337706;12514.124011319302;16482.2582983766;17202.13989300555;17468.126593670266;17479.737803932952;17570.472885813448;5994.820155395345;6002.279726432856;6050.3949605040025;6077.3314935357075;6115.571909966348;970.6408628807386;1001.9398836069859;1010.3382284603597;1043.7495001199657;1059.782744537368;31963.840180799412;32260.677393225767;32386.83806580952;32394.190145246084;33010;1098.3903933317085;1109.877913429522;1116.1238427834273;1126.3692332293779;1144.2962578014628;16663.000659881094;16790.412335568195;16961.575960601083;17102.315907363816;17436.82563174352;566059;576971;578229;876654;882312;59553.40446595565;59709.70145149333;60198.699006505565;60282.397176027676;60422.395776042555;1331.1148086522549;1350.709597436556;1359.4969861151437;1362.1758835904334;1381.4681347681214;32165.678343216267;33280.500792488616;33380.833095834365;33553.83223083869;33757;51046;51395;51398.74300628473;51545.48454515407;51600.48399516107;430889;434078;435932;437446;437931;27174.864125679243;28040.57939130874;28047.158585242476;28092.85953570219;29691.85154074216;48178;48843.75578122087;48965.510344896094;48967.75516122397;49041.50958490512;37564.44735004335;42239.66405039269;42862.78568607137;43662.781686091366;44107.77946110249;42291.57708423;44419;44733.329000064376;45372.31941520814;45814.77092614515;42866.357004644335;43511.347329789445;43571;43759.562404376826;44989.550104499845;43670;43715.90710232143;43913;44275;44331;28929;29970.850145749133;30084.69915300819;30240.69759302379;30486.847565762917 22:36:35 INFO - PROCESS | 20523 | |2;jquery.html;376.6798221511714;381.862529489384;396.7877185705647;397.85876014015025;414.48810718762326;13748;18507.629847403045;18759.81240187601;19987.20051197954;20041.799582004176;17889.552761180978;18109.547261318472;18417.26330946764;19298.035098245065;19348.90325548374;5289.180177072541;5454.699991500466;5747.224124743158;5950.494207992326;5957.046872500401;5488.53347465466;5569.275994120779;5579.107342825148;5601.467860553241;5609.49514543691;5517.972410137943;5540.722963851811;5596.132599447093;5602.467765562285;5677.602567820254;5269.420363759983;5516.282883225195;5668.206451096849;5686.516646085088;5691.08942569189;5363.919541206885;5435.0488664483555;5449.3460784706085;5460.290162278879;5468.835934921959;13141.802872956914;13719.519816806363;22197.445063873372;23042.769572304398;23074.307770766904;2193.5722534105857;2360.976390236093;2491.6262560615874;2529.1148098165677;2854.9143525694262;9266.258699304039;9313.627454901864;9377.718668439958;9690.418574885529;14247.572572822832;1809.3215044358365;1845.9538511537185;1898.8196121368449;1969.9901500492406;1976.883061070505;81104.18895811026;83154.58422707848;83939;85244;86467.56766216192;10519.31624444424;10609.575616975235;10865.185111116698;10874.347539147677;10947.83578246327;14970.775438368433;15106.169160696047;15108;15132.621684457756;15172.620684482976;7749.4575379724;8030.116687164407;8037.437379383387;8081.838363232702;8170.264676179172;7592.202818704067;7696.884546731803;7811.687532498637;7887.9605601971625;7909.564973926489;4437.5784300491405;4605.263157894721;4647.442306923191;4687.226607609706;4717.268823332389;1799.901005444687;1847.9260829566936;1939.6217737541044;1942.6794578894323;1972.2308299763104;926.7219834049758;959.2230293462318;1002.1682003936716;1014.9594016239415;1034.6585626743308;18948;19381;19444;19462;19787.604247914962;963.6868017894212;996.4619105682995;1066.3495267886583;1073.897979691928;1079.5411949921252;9870.506474676382;10730.316782389626;10764.1209169254;10782.417189964597;10829.65257271472;459102;474591;501849;506779;509828;90991.54504227436;92419;94563.52718236364;96337;97368;987.511181964932;1024.3495380433478;1054.4095306628312;1059.369675043348;1061.4480470155477;16611.41860034893;16979.66040679204;17734.556636084202;17768;18148.092595370443;40485.39271910983;41205.793971029954;41831.954201145214;42251;42586.57413425826;55803;56438.1534276978;59902.4058556488;59910.80178396495;61118.388816113045;6940.9652951734915;7979.601019948863;8011.879821802561;8070.192980702118;8155.388345873965;10062.69811905644;12584.685382865502;12728.1726687765;12918.289494077915;13056.151350162208;11633.825492617449;11790.351530665896;11908.380156365585;11971;12008.879911201126;10626.25616206889;11442.141839362248;11621.922763679162;11704.824427633423;11870.821937671115;11772.234804737665;11788.351640659846;11815;11846.822297665369;12096.45565949552;7217.819554511179;7505.774826755206;7545.622718863925;7584;7859.567723775247;9719.271054670786;11413.144014199135;11556.826647600461;11955.342456164994;11965.282083075042 22:36:35 INFO - PROCESS | 20523 | |3;mootools.html;1434.8206474190727;1485.9479918202862;1595.6649103688226;1784.072282413146;1791.390927084791;7764.728234511793;8660.870086948693;8769.824603507926;9268.58291376888;9274.258059355252;8838.160374764404;9143.497107659075;9145.131212534816;9164.312676549269;9184.632614695422;2274.3404412720333;2327.3483424641126;2340.040583360814;2347.307544274435;2353.9058437662475;2305.7694230576976;2322.268485427085;2328.359701082199;2352.6118190498632;2361.3860396297;2299.8045166160814;2305.250793492113;2328.0338659456306;2338.6024375856146;2349.6710460535455;2241.12596087526;2249.932502024942;2250.707408036945;2261.694671219389;2264.29806759905;2164.199246278871;2164.6211912915333;2167.7398712154486;2175.869447833135;2177.5318306564163;9151.633934642672;9204.861927071099;9205.585748641328;9230.953845230732;9258.444493330422;5106.974465127651;5226.608004399647;5260.815871444481;5270.209468579706;5274.578033757329;3263.8857639982725;3313.8177400242976;3416.9487457688156;3441.6558344165383;3450.344434557426;1118.8545489086366;1128.2722643894642;1130.8473356096986;1138.1463902073444;1165.8775828537966;12762.936185319015;12777;12812.359382030954;12834.80747788784;12854.807177892339;4390.868273951786;4467.396901418332;4581.862544123682;4627.814887404466;4685.625149987993;10699.251052426256;10744.570217191225;10802.51388687511;10811.972862578043;10833.729156771144;5243.528082472596;5254.133068043807;5254.632175747737;5258;5261;4341.869743907688;4615.215413379734;4709.199436095803;4723.504032076651;4795.664303498791;3930.3514920038447;4043.7978101094827;4111.527174374925;4156.085661154541;4166.562510936369;1285.1068507387276;1320.6764342736092;1327.4424741608548;1328.926909019994;1337.5987203839004;829.5852073963018;845.7293666026812;932.1843387036344;948.170350942925;963.1379914976197;5922.674252916045;5993.160957465958;6039.758409663652;6075.5747097703625;6156.583985683318;390.9706771992112;393.5631449091506;396.41330830371214;411.06482751739435;416.17389481878445;4197.118605092904;4211.642010429148;4222.092250166229;4238.724482908663;4285.271503844354;11006.724831879266;11315;11441.771164576661;11446.08431325492;11465.885341146482;10697.197710171768;11087.667369978913;11295.152863535268;11317.49071291794;11352.829707554394;8267.338612910953;8578.571071446402;8586.356023298153;8587.570621468902;8623.051464338918;5794.797182098607;5798.6810725410505;5990.161377407254;6013.248343957005;6131.662758548323;10025.849612255968;10226;10267.127294180078;10293.794124117327;10319.174466042849;10576.153907687212;10794.298370605886;10806.459677016275;10812.135029197803;10823.404712740874;7127.821804454723;7698.384129269757;7769.067711874612;7884.132745398117;8050.879236811335;8677.696280630147;8806.60370283326;8815.471071735716;8843.51360675169;8882.422642528216;7655.425843061681;7849.529028258323;7943.721969731032;7973.880391794244;8070.152633973583;7434.888476672746;7750.806229844299;7812.335951444191;7879.803004924694;7902.802429939297;7012.894806577803;7073.6463176839925;7124.821879453055;7125.501214914906;7131.607761573163;5157.484251574812;5170.560502357342;5266;5282.418933917189;5441.2654291670115;5128.743562821918;5152.458991805806;5168.457311982188;5250.159974404077;5457 22:36:35 INFO - PROCESS | 20523 | |4;prototype.html;298.695330762622;317.64423845293277;319.0794557701032;323.32425231266654;326.9297101123259;5402.540784033362;5565.526930210932;5575.8884822303635;5638.407967163445;5699.629524080932;5537.335519737627;5559.9444005559935;5560.388357280696;5564;5581.134924086763;4279.3367028110615;4287.957120428802;4289.549597292286;4290.313549832027;4292.484901811787;4120.855770048049;4240.97879510602;4259.616634502894;4263.722858014219;4280.507741609724;4210.347396153601;4224.064275328647;4227;4229.429027081351;4244.72658202538;4186.979065104655;4194.958050419503;4220.240356735786;4231.513375961757;4262.1688770689725;3621;3703.370427027412;3713.610070942539;3721.5162028936065;3738.9904725723904;5435.918461223085;5467.664724518041;5469.589780766419;5474.562035037187;5478.411260734382;4853.830115945925;4858.975705121487;4863.440704319013;4885.951140488585;4889.975550122226;5553.583481238923;5766.538676905838;5807.216025836543;5843.883122337529;5850.619709718897;4040.4141399496907;4070.6743460523385;4071.531773846016;4073.1039171382545;4077.7553346799286;6010.128531362928;6039.516838652898;6044.455998960071;6057.636541807506;6067.271927368744;4063.167050754588;4122.896927576835;4159.396887451303;4165.896222407665;4166.854160104443;4187.895302617459;4193.517745459311;4196;4197.118605092965;4224.704270701083;4815.7351345676325;4836.613070954386;4844.128056949712;4851.539103785135;4865.440474345425;5738.737477754888;5889.263842019747;5891.558133140032;5893.027650437715;5920.171176035358;1459.292243262017;1489.054450423967;1493.701259748033;1493.7161939231512;1499.3327969053666;885.3536918049731;924.4591913730409;927.5037854747812;930.6789157740569;937.5968333616611;4965.428975667773;5005.874853128701;5023.572996295283;5024.84925452237;5075.046107248736;5274;5304.939012197576;5308.708021058802;5321.388040375329;5330.466953304638;4697.647676424282;4992.72540010303;5025.7989680412375;5064.772085256172;5071.290019397287;2778.6387769589824;2787.846668433215;2794.3712664650293;2811.536096544088;2824.5904343870025;5392.842862854811;5397;5408.45915408456;5422.674639521641;5441.564674826004;5083.288339632454;5237.6333656643665;5292.894142117136;5325.680459172462;5358.089124848785;5316.255724198615;5353.678779273178;5358.27663265462;5371.758270877898;5405.243265942771;5206.4901178657765;5226.608004399609;5243.659162154448;5255.710935898409;5264.499872512105;4887.511248875227;5157.7679004444135;5172.948270517397;5188;5208.765605547836;5306.761195746278;5310.707911064928;5325.573954083741;5330.747274390437;5348.747745542381;3774.50931378919;3996.1807829394324;4130.194612050621;4247.853079668534;4249.532551419403;5900.232969714081;5952.732127054206;5964.522838172849;5974.790882319098;5982.88034239304;5607.009511623315;5799.594028418139;5815.738291776965;5824.563157763269;5824.854378640568;5734.598578099493;5750.856228594319;5753.942460575341;5775.94224057754;5793.797217097382;5654.660720356792;5666;5697.259356283656;5725.770969161187;5741.282339707536;5609.23309157614;5759.827205183851;5762;5779.219805326227;5794.319647302235;5623.325200975909;5665.943340566541;5667.234923285304;5670.9432905672065;5698.1737648041635 22:36:35 INFO - PROCESS | 20523 | |5;yui.html;733.9229380915001;747.3460721868365;751.8909758085077;784.6861255497801;849.7378573548323;15471.767923481142;16751.246193921274;16786.580335491617;16822.411215607455;16857.662846743075;15182.089074655529;15415.152166630858;15488.535343939671;15491.380344786196;15491.767623485657;667.4927055437854;793.3415265329792;799.6641410607559;816.6447595296036;822.9753107406788;815.4862436664893;820.0446479850966;820.9179082091803;827.5076329583887;829.3945419843487;880;885.5926273914007;886.3573908916048;889.3196704521014;889.6308032166643;884.3897710579712;885.5085427587686;885.8494056010494;886.1006078829993;886.2068448738343;641.7240586547782;644.7549931026227;648.5492582655056;652.1228947066188;652.1456891472187;14084.788728169086;14086.013979021473;14098.295085245696;14115.929420352833;14167.07913985598;14957.775633365509;15082.321295541728;15108;15130.470433534883;15336;11925.701857453547;11952.880471195263;11981.700457488545;12392.690182745413;12432.378381081;3987.8803635890968;4019.7588144711412;4036.576159503239;4107.301758701028;4202.327627579603;67846.66076669584;69846;70255.29744702538;78181;78338;11570.42147892602;12059.457324420422;12170.452329645188;12205.572804951784;12213;21626.891865540885;21637.86250993597;21649.025793839315;21833.563328733337;21850.672239916414;8072.273495385443;8172.6730930762105;8250.437976970652;8721.651133954698;8832.646694132163;4635.2120139576355;4672.439307283148;4674.275487299474;4712.858614241578;4813.590844778163;3615.6745892869244;3728.067983004249;3745.7190710696805;3751.2685026419585;3762.7930463824205;644.6196743921015;649.3441623959834;660.7918505670698;661.1141070964931;667.8998150277497;614.2720875762236;641.4547634510628;648.670226036623;650.5511197273865;679.3104998426601;9664.323497355259;9881.703548893545;9897.752556186153;9935.205183585296;9949.502524873873;532.1868809976552;544.6595877576515;604.6613896218132;626.5175814622806;743.5129989856653;4495.977520112378;4510.977445112819;4538.909221815546;4583.613828059988;4639.768011599474;65666.34333656698;109081.90918090717;117722.41138794423;118448.40775796065;121467;56548.71725641345;57865;69831;90097;100042;84781.15218847856;85173.57413212894;85409.57295213484;85855.57072214599;92266.53866730623;8575.228229459379;8695.260902823331;8827.426217295855;8841;8975.102489750972;53139.20291195558;53306.73346633397;53705.731471343955;54516;55051.44948550463;69353;69489;78504.21495785197;79104;79810.60094699722;11359.432028398713;11685.824712629488;11704.403236984956;11852.76294474123;11930.642080737593;11923.880761192624;12084.879151208726;15177.317020733874;15438.073715577102;15853.52439426819;14935.85064149388;15009.2495375233;15070.321835517647;15100.54698359051;15216.543503694907;14745.483908063166;14843.257837107885;15015.849841501446;15045.699086018438;15279.236038197823;13037.217766933634;14271;14423.42306307736;14527.636809079857;14760.188189649672;11316;11883.821742673694;11897.940510297392;11951.402429878297;12554.183978041397;11533.596324128615;12210.206336588093;12314.876851231731;12488.313142776871;12494.750104997667 22:36:35 INFO - PROCESS | 20523 | __end_tp_report 22:36:35 INFO - PROCESS | 20523 | __start_cc_report 22:36:35 INFO - PROCESS | 20523 | _x_x_mozilla_cycle_collect,370 22:36:35 INFO - PROCESS | 20523 | __end_cc_report 22:36:35 INFO - PROCESS | 20523 | __startTimestamp1469252195550__endTimestamp 22:36:35 INFO - PROCESS | 20523 | 22:36:35 INFO - PROCESS | 20523 | ------- Summary: start ------- 22:36:35 INFO - PROCESS | 20523 | Number of tests: 6 22:36:35 INFO - PROCESS | 20523 | 22:36:35 INFO - PROCESS | 20523 | [#0] dojo.html Cycles:165 Average:5479.03 Median:5700.89 stddev:634.62 (11.1%) stddev-sans-first:608.09 22:36:35 INFO - PROCESS | 20523 | Values: 3083.1 3084.2 3105.6 3116.2 3119.0 5421.9 5500.2 5501.9 5503.9 5523.7 5414.8 5423.4 5448.8 5495.8 5555.2 4935.6 4986.4 5098.9 5131.4 5140.0 5011.2 5156.7 5157.9 5167.3 5168.9 4932.1 4933.2 5030.6 5050.0 5114.5 4915.1 4932.9 4940.8 4951.0 5070.8 4943.9 4954.4 4992.0 4997.2 5038.7 5355.9 5432.7 5472.2 5481.6 5527.2 5861.7 5862.9 5864.1 5869.3 5906.9 5862.6 5906.0 5910.3 5910.5 5910.9 5370.2 5466.1 5494.8 5522.9 5547.0 6064.6 6071.5 6073.0 6081.1 6107.2 5802.4 5824.3 5825.4 5849.4 5869.8 5807.4 5882.5 5919.1 5923.4 5927.7 5682.7 5860.2 5884.9 5911.3 5971.8 5906.8 5916.8 5957.8 5976.0 6012.7 4413.7 4441.1 4462.8 4513.0 4521.0 3941.1 3987.2 4010.9 4021.9 4027.0 5539.4 5616.0 5654.9 5656.7 5664.7 5730.9 5900.6 5924.0 5937.8 5938.2 5642.8 5724.1 5737.9 5748.7 5764.7 5151.4 5157.7 5163.9 5164.6 5172.4 5946.6 5952.8 6149.0 6167.3 6170.0 5855.5 5939.1 5959.8 5991.0 6005.9 5989.8 6038.3 6068.7 6071.1 6087.5 5636.6 5731.2 5798.8 5818.6 5820.0 5870.9 5938.5 5943.3 5958.8 6008.0 5700.9 5770.3 5782.8 5786.5 5786.9 5664.6 5674.0 5742.7 5774.7 5779.9 5646.5 5692.8 5700.8 5728.3 5741.3 5767.7 5787.7 5796.2 5825.3 5827.1 5535.7 5604.8 5634.4 5638.2 5706.3 22:36:35 INFO - PROCESS | 20523 | 22:36:35 INFO - PROCESS | 20523 | [#1] ext.html Cycles:180 Average:64767.81 Median:239751.50 stddev:139331.82 (58.1%) stddev-sans-first:139639.24 22:36:35 INFO - PROCESS | 20523 | Values: 540.3 581.2 590.9 602.7 622.1 39029.6 43015.0 43031.4 43089.1 43215.0 43373.1 43507.6 43766.0 43797.8 43922.6 8662.0 8849.7 8850.2 8853.3 8854.0 8602.2 8726.3 8746.1 8751.9 8795.0 8743.0 8803.8 8807.0 8821.1 8823.2 8777.8 8783.1 8815.6 8816.6 8856.9 8721.1 8731.1 8773.5 8774.8 8777.8 40638.6 41018.4 41133.4 41254.2 41338.8 3618.0 3704.5 3762.9 3766.3 4120.1 32645.9 32760.5 32869.7 32871.0 32947.5 4326.1 4423.9 4462.6 4468.6 4599.8 335314.0 336605.0 339172.0 339177.0 341495.0 57795.4 58233.8 58302.7 58302.7 58391.7 60759.4 61138.0 61580.1 61775.4 61867.0 15063.4 15065.7 15262.7 15266.9 15296.2 12378.0 12417.6 12489.4 12512.4 12514.1 16482.3 17202.1 17468.1 17479.7 17570.5 5994.8 6002.3 6050.4 6077.3 6115.6 970.6 1001.9 1010.3 1043.7 1059.8 31963.8 32260.7 32386.8 32394.2 33010.0 1098.4 1109.9 1116.1 1126.4 1144.3 16663.0 16790.4 16961.6 17102.3 17436.8 566059.0 576971.0 578229.0 876654.0 882312.0 59553.4 59709.7 60198.7 60282.4 60422.4 1331.1 1350.7 1359.5 1362.2 1381.5 32165.7 33280.5 33380.8 33553.8 33757.0 51046.0 51395.0 51398.7 51545.5 51600.5 430889.0 434078.0 435932.0 437446.0 437931.0 27174.9 28040.6 28047.2 28092.9 29691.9 48178.0 48843.8 48965.5 48967.8 49041.5 37564.4 42239.7 42862.8 43662.8 44107.8 42291.6 44419.0 44733.3 45372.3 45814.8 42866.4 43511.3 43571.0 43759.6 44989.6 43670.0 43715.9 43913.0 44275.0 44331.0 28929.0 29970.9 30084.7 30240.7 30486.8 22:36:35 INFO - PROCESS | 20523 | 22:36:35 INFO - PROCESS | 20523 | [#2] jquery.html Cycles:180 Average:28909.36 Median:389.33 stddev:81092.85 (20829.1%) stddev-sans-first:81292.04 22:36:35 INFO - PROCESS | 20523 | Values: 376.7 381.9 396.8 397.9 414.5 13748.0 18507.6 18759.8 19987.2 20041.8 17889.6 18109.5 18417.3 19298.0 19348.9 5289.2 5454.7 5747.2 5950.5 5957.0 5488.5 5569.3 5579.1 5601.5 5609.5 5518.0 5540.7 5596.1 5602.5 5677.6 5269.4 5516.3 5668.2 5686.5 5691.1 5363.9 5435.0 5449.3 5460.3 5468.8 13141.8 13719.5 22197.4 23042.8 23074.3 2193.6 2361.0 2491.6 2529.1 2854.9 9266.3 9313.6 9377.7 9690.4 14247.6 1809.3 1846.0 1898.8 1970.0 1976.9 81104.2 83154.6 83939.0 85244.0 86467.6 10519.3 10609.6 10865.2 10874.3 10947.8 14970.8 15106.2 15108.0 15132.6 15172.6 7749.5 8030.1 8037.4 8081.8 8170.3 7592.2 7696.9 7811.7 7888.0 7909.6 4437.6 4605.3 4647.4 4687.2 4717.3 1799.9 1847.9 1939.6 1942.7 1972.2 926.7 959.2 1002.2 1015.0 1034.7 18948.0 19381.0 19444.0 19462.0 19787.6 963.7 996.5 1066.3 1073.9 1079.5 9870.5 10730.3 10764.1 10782.4 10829.7 459102.0 474591.0 501849.0 506779.0 509828.0 90991.5 92419.0 94563.5 96337.0 97368.0 987.5 1024.3 1054.4 1059.4 1061.4 16611.4 16979.7 17734.6 17768.0 18148.1 40485.4 41205.8 41832.0 42251.0 42586.6 55803.0 56438.2 59902.4 59910.8 61118.4 6941.0 7979.6 8011.9 8070.2 8155.4 10062.7 12584.7 12728.2 12918.3 13056.2 11633.8 11790.4 11908.4 11971.0 12008.9 10626.3 11442.1 11621.9 11704.8 11870.8 11772.2 11788.4 11815.0 11846.8 12096.5 7217.8 7505.8 7545.6 7584.0 7859.6 9719.3 11413.1 11556.8 11955.3 11965.3 22:36:35 INFO - PROCESS | 20523 | 22:36:35 INFO - PROCESS | 20523 | [#3] mootools.html Cycles:180 Average:5903.63 Median:4366.37 stddev:3460.68 (79.3%) stddev-sans-first:3454.09 22:36:35 INFO - PROCESS | 20523 | Values: 1434.8 1485.9 1595.7 1784.1 1791.4 7764.7 8660.9 8769.8 9268.6 9274.3 8838.2 9143.5 9145.1 9164.3 9184.6 2274.3 2327.3 2340.0 2347.3 2353.9 2305.8 2322.3 2328.4 2352.6 2361.4 2299.8 2305.3 2328.0 2338.6 2349.7 2241.1 2249.9 2250.7 2261.7 2264.3 2164.2 2164.6 2167.7 2175.9 2177.5 9151.6 9204.9 9205.6 9231.0 9258.4 5107.0 5226.6 5260.8 5270.2 5274.6 3263.9 3313.8 3416.9 3441.7 3450.3 1118.9 1128.3 1130.8 1138.1 1165.9 12762.9 12777.0 12812.4 12834.8 12854.8 4390.9 4467.4 4581.9 4627.8 4685.6 10699.3 10744.6 10802.5 10812.0 10833.7 5243.5 5254.1 5254.6 5258.0 5261.0 4341.9 4615.2 4709.2 4723.5 4795.7 3930.4 4043.8 4111.5 4156.1 4166.6 1285.1 1320.7 1327.4 1328.9 1337.6 829.6 845.7 932.2 948.2 963.1 5922.7 5993.2 6039.8 6075.6 6156.6 391.0 393.6 396.4 411.1 416.2 4197.1 4211.6 4222.1 4238.7 4285.3 11006.7 11315.0 11441.8 11446.1 11465.9 10697.2 11087.7 11295.2 11317.5 11352.8 8267.3 8578.6 8586.4 8587.6 8623.1 5794.8 5798.7 5990.2 6013.2 6131.7 10025.8 10226.0 10267.1 10293.8 10319.2 10576.2 10794.3 10806.5 10812.1 10823.4 7127.8 7698.4 7769.1 7884.1 8050.9 8677.7 8806.6 8815.5 8843.5 8882.4 7655.4 7849.5 7943.7 7973.9 8070.2 7434.9 7750.8 7812.3 7879.8 7902.8 7012.9 7073.6 7124.8 7125.5 7131.6 5157.5 5170.6 5266.0 5282.4 5441.3 5128.7 5152.5 5168.5 5250.2 5457.0 22:36:35 INFO - PROCESS | 20523 | 22:36:35 INFO - PROCESS | 20523 | [#4] prototype.html Cycles:180 Average:4674.31 Median:5260.11 stddev:1364.95 (25.9%) stddev-sans-first:1328.68 22:36:35 INFO - PROCESS | 20523 | Values: 298.7 317.6 319.1 323.3 326.9 5402.5 5565.5 5575.9 5638.4 5699.6 5537.3 5559.9 5560.4 5564.0 5581.1 4279.3 4288.0 4289.5 4290.3 4292.5 4120.9 4241.0 4259.6 4263.7 4280.5 4210.3 4224.1 4227.0 4229.4 4244.7 4187.0 4195.0 4220.2 4231.5 4262.2 3621.0 3703.4 3713.6 3721.5 3739.0 5435.9 5467.7 5469.6 5474.6 5478.4 4853.8 4859.0 4863.4 4886.0 4890.0 5553.6 5766.5 5807.2 5843.9 5850.6 4040.4 4070.7 4071.5 4073.1 4077.8 6010.1 6039.5 6044.5 6057.6 6067.3 4063.2 4122.9 4159.4 4165.9 4166.9 4187.9 4193.5 4196.0 4197.1 4224.7 4815.7 4836.6 4844.1 4851.5 4865.4 5738.7 5889.3 5891.6 5893.0 5920.2 1459.3 1489.1 1493.7 1493.7 1499.3 885.4 924.5 927.5 930.7 937.6 4965.4 5005.9 5023.6 5024.8 5075.0 5274.0 5304.9 5308.7 5321.4 5330.5 4697.6 4992.7 5025.8 5064.8 5071.3 2778.6 2787.8 2794.4 2811.5 2824.6 5392.8 5397.0 5408.5 5422.7 5441.6 5083.3 5237.6 5292.9 5325.7 5358.1 5316.3 5353.7 5358.3 5371.8 5405.2 5206.5 5226.6 5243.7 5255.7 5264.5 4887.5 5157.8 5172.9 5188.0 5208.8 5306.8 5310.7 5325.6 5330.7 5348.7 3774.5 3996.2 4130.2 4247.9 4249.5 5900.2 5952.7 5964.5 5974.8 5982.9 5607.0 5799.6 5815.7 5824.6 5824.9 5734.6 5750.9 5753.9 5775.9 5793.8 5654.7 5666.0 5697.3 5725.8 5741.3 5609.2 5759.8 5762.0 5779.2 5794.3 5623.3 5665.9 5667.2 5670.9 5698.2 22:36:35 INFO - PROCESS | 20523 | 22:36:35 INFO - PROCESS | 20523 | [#5] yui.html Cycles:180 Average:20061.91 Median:4656.10 stddev:27944.46 (600.2%) stddev-sans-first:27985.16 22:36:35 INFO - PROCESS | 20523 | Values: 733.9 747.3 751.9 784.7 849.7 15471.8 16751.2 16786.6 16822.4 16857.7 15182.1 15415.2 15488.5 15491.4 15491.8 667.5 793.3 799.7 816.6 823.0 815.5 820.0 820.9 827.5 829.4 880.0 885.6 886.4 889.3 889.6 884.4 885.5 885.8 886.1 886.2 641.7 644.8 648.5 652.1 652.1 14084.8 14086.0 14098.3 14115.9 14167.1 14957.8 15082.3 15108.0 15130.5 15336.0 11925.7 11952.9 11981.7 12392.7 12432.4 3987.9 4019.8 4036.6 4107.3 4202.3 67846.7 69846.0 70255.3 78181.0 78338.0 11570.4 12059.5 12170.5 12205.6 12213.0 21626.9 21637.9 21649.0 21833.6 21850.7 8072.3 8172.7 8250.4 8721.7 8832.6 4635.2 4672.4 4674.3 4712.9 4813.6 3615.7 3728.1 3745.7 3751.3 3762.8 644.6 649.3 660.8 661.1 667.9 614.3 641.5 648.7 650.6 679.3 9664.3 9881.7 9897.8 9935.2 9949.5 532.2 544.7 604.7 626.5 743.5 4496.0 4511.0 4538.9 4583.6 4639.8 65666.3 109081.9 117722.4 118448.4 121467.0 56548.7 57865.0 69831.0 90097.0 100042.0 84781.2 85173.6 85409.6 85855.6 92266.5 8575.2 8695.3 8827.4 8841.0 8975.1 53139.2 53306.7 53705.7 54516.0 55051.4 69353.0 69489.0 78504.2 79104.0 79810.6 11359.4 11685.8 11704.4 11852.8 11930.6 11923.9 12084.9 15177.3 15438.1 15853.5 14935.9 15009.2 15070.3 15100.5 15216.5 14745.5 14843.3 15015.8 15045.7 15279.2 13037.2 14271.0 14423.4 14527.6 14760.2 11316.0 11883.8 11897.9 11951.4 12554.2 11533.6 12210.2 12314.9 12488.3 12494.8 22:36:35 INFO - PROCESS | 20523 | -------- Summary: end -------- 22:36:35 INFO - PROCESS | 20523 | 22:36:36 INFO - TEST-INFO | 20523: exit 0 22:36:36 INFO - TEST-OK | dromaeo_css | took 1111848ms 22:36:36 INFO - TEST-START | kraken 22:36:36 INFO - operating with platform_type : linux_ 22:36:36 INFO - Initialising browser for kraken test... 22:36:36 INFO - TEST-INFO | started process 20767 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWFJOK8/profile http://localhost:53275/getInfo.html) 22:36:39 INFO - PROCESS | 20767 | JavaScript error: , line 0: uncaught exception: undefined 22:36:40 INFO - PROCESS | 20767 | __metrics Screen width/height:1600/1200 22:36:40 INFO - PROCESS | 20767 | colorDepth:24 22:36:40 INFO - PROCESS | 20767 | Browser inner width/height: 1024/697 22:36:40 INFO - PROCESS | 20767 | __metrics 22:36:41 ERROR - PROCESS | 20767 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2968: TypeError: this.tabs is undefined 22:36:42 INFO - TEST-INFO | 20767: exit 0 22:36:42 INFO - Browser initialized. 22:36:42 INFO - Running cycle 1/1 for kraken test... 22:36:42 INFO - TEST-INFO | started process 20886 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpWFJOK8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:36:54 INFO - PROCESS | 20886 | RSS: Main: 168017920 22:36:54 INFO - PROCESS | 20886 | 22:36:56 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53275/tests/kraken/driver.html?testName=audio-beat-detection) 22:36:57 INFO - PROCESS | 20886 | RSS: Main: 173043712 22:36:57 INFO - PROCESS | 20886 | 22:37:00 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53275/tests/kraken/driver.html?testName=audio-dft) 22:37:00 INFO - PROCESS | 20886 | RSS: Main: 171401216 22:37:00 INFO - PROCESS | 20886 | 22:37:03 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53275/tests/kraken/driver.html?testName=audio-fft) 22:37:04 INFO - PROCESS | 20886 | RSS: Main: 168353792 22:37:04 INFO - PROCESS | 20886 | 22:37:06 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53275/tests/kraken/driver.html?testName=audio-oscillator) 22:37:07 INFO - PROCESS | 20886 | RSS: Main: 169459712 22:37:07 INFO - PROCESS | 20886 | 22:37:09 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:37:09 INFO - PROCESS | 20886 | RSS: Main: 169619456 22:37:09 INFO - PROCESS | 20886 | 22:37:12 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53275/tests/kraken/driver.html?testName=imaging-darkroom) 22:37:13 INFO - PROCESS | 20886 | RSS: Main: 168792064 22:37:13 INFO - PROCESS | 20886 | 22:37:16 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53275/tests/kraken/driver.html?testName=imaging-desaturate) 22:37:17 INFO - PROCESS | 20886 | RSS: Main: 167051264 22:37:17 INFO - PROCESS | 20886 | 22:37:20 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53275/tests/kraken/driver.html?testName=json-parse-financial) 22:37:21 INFO - PROCESS | 20886 | RSS: Main: 168542208 22:37:21 INFO - PROCESS | 20886 | 22:37:22 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:37:23 INFO - PROCESS | 20886 | RSS: Main: 169025536 22:37:23 INFO - PROCESS | 20886 | 22:37:25 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53275/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:37:25 INFO - PROCESS | 20886 | RSS: Main: 169340928 22:37:25 INFO - PROCESS | 20886 | 22:37:27 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:37:28 INFO - PROCESS | 20886 | RSS: Main: 169652224 22:37:28 INFO - PROCESS | 20886 | 22:37:31 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:37:31 INFO - PROCESS | 20886 | RSS: Main: 169594880 22:37:31 INFO - PROCESS | 20886 | 22:37:34 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:37:35 INFO - PROCESS | 20886 | RSS: Main: 169644032 22:37:35 INFO - PROCESS | 20886 | 22:37:36 INFO - PROCESS | 20886 | Cycle 1(1): loaded http://localhost:53275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53275/tests/kraken/driver.html?testName=ai-astar) 22:37:36 INFO - PROCESS | 20886 | RSS: Main: 170418176 22:37:36 INFO - PROCESS | 20886 | 22:37:36 INFO - PROCESS | 20886 | __start_tp_report 22:37:36 INFO - PROCESS | 20886 | _x_x_mozilla_page_load 22:37:36 INFO - PROCESS | 20886 | _x_x_mozilla_page_load_details 22:37:36 INFO - PROCESS | 20886 | |i|pagename|runs| 22:37:36 INFO - PROCESS | 20886 | |0;ai-astar;103;112;107;112;113;113;113;113;114;111 22:37:36 INFO - PROCESS | 20886 | |1;audio-beat-detection;146;122;145;179;119;152;151;129;185;118 22:37:36 INFO - PROCESS | 20886 | |2;audio-dft;162;163;163;162;162;163;176;164;162;162 22:37:36 INFO - PROCESS | 20886 | |3;audio-fft;77;76;74;143;76;74;75;74;180;76 22:37:36 INFO - PROCESS | 20886 | |4;audio-oscillator;84;85;149;84;85;84;133;84;85;139 22:37:36 INFO - PROCESS | 20886 | |5;imaging-gaussian-blur;117;118;117;117;118;117;118;118;117;118 22:37:36 INFO - PROCESS | 20886 | |6;imaging-darkroom;147;147;147;148;148;146;147;148;147;147 22:37:36 INFO - PROCESS | 20886 | |7;imaging-desaturate;96;96;96;100;96;96;95;96;97;96 22:37:36 INFO - PROCESS | 20886 | |8;json-parse-financial;54;54;56;56;59;53;59;54;53;52 22:37:36 INFO - PROCESS | 20886 | |9;json-stringify-tinderbox;62;63;61;61;64;61;62;61;62;62 22:37:36 INFO - PROCESS | 20886 | |10;stanford-crypto-aes;79;78;77;77;82;76;77;76;83;82 22:37:36 INFO - PROCESS | 20886 | |11;stanford-crypto-ccm;132;121;123;139;134;137;129;122;165;128 22:37:36 INFO - PROCESS | 20886 | |12;stanford-crypto-pbkdf2;168;163;160;159;162;158;163;157;158;163 22:37:36 INFO - PROCESS | 20886 | |13;stanford-crypto-sha256-iterative;64;67;74;65;70;63;67;65;65;63 22:37:36 INFO - PROCESS | 20886 | __end_tp_report 22:37:36 INFO - PROCESS | 20886 | __start_cc_report 22:37:36 INFO - PROCESS | 20886 | _x_x_mozilla_cycle_collect,893 22:37:37 INFO - PROCESS | 20886 | __end_cc_report 22:37:37 INFO - PROCESS | 20886 | __startTimestamp1469252256989__endTimestamp 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | ------- Summary: start ------- 22:37:37 INFO - PROCESS | 20886 | Number of tests: 14 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#0] ai-astar Cycles:10 Average:111.10 Median:113.00 stddev:3.45 (3.0%) stddev-sans-first:2.06 22:37:37 INFO - PROCESS | 20886 | Values: 103.0 112.0 107.0 112.0 113.0 113.0 113.0 113.0 114.0 111.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#1] audio-beat-detection Cycles:10 Average:144.60 Median:148.50 stddev:23.64 (15.9%) stddev-sans-first:25.07 22:37:37 INFO - PROCESS | 20886 | Values: 146.0 122.0 145.0 179.0 119.0 152.0 151.0 129.0 185.0 118.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#2] audio-dft Cycles:10 Average:163.90 Median:163.00 stddev:4.31 (2.6%) stddev-sans-first:4.51 22:37:37 INFO - PROCESS | 20886 | Values: 162.0 163.0 163.0 162.0 162.0 163.0 176.0 164.0 162.0 162.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#3] audio-fft Cycles:10 Average:92.50 Median:75.50 stddev:37.41 (49.6%) stddev-sans-first:39.26 22:37:37 INFO - PROCESS | 20886 | Values: 77.0 76.0 74.0 143.0 76.0 74.0 75.0 74.0 180.0 76.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#4] audio-oscillator Cycles:10 Average:101.20 Median:84.00 stddev:27.28 (32.5%) stddev-sans-first:28.21 22:37:37 INFO - PROCESS | 20886 | Values: 84.0 85.0 149.0 84.0 85.0 84.0 133.0 84.0 85.0 139.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:118.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 22:37:37 INFO - PROCESS | 20886 | Values: 117.0 118.0 117.0 117.0 118.0 117.0 118.0 118.0 117.0 118.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 22:37:37 INFO - PROCESS | 20886 | Values: 147.0 147.0 147.0 148.0 148.0 146.0 147.0 148.0 147.0 147.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:96.00 stddev:1.35 (1.4%) stddev-sans-first:1.42 22:37:37 INFO - PROCESS | 20886 | Values: 96.0 96.0 96.0 100.0 96.0 96.0 95.0 96.0 97.0 96.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#8] json-parse-financial Cycles:10 Average:55.00 Median:55.00 stddev:2.45 (4.5%) stddev-sans-first:2.57 22:37:37 INFO - PROCESS | 20886 | Values: 54.0 54.0 56.0 56.0 59.0 53.0 59.0 54.0 53.0 52.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:62.00 stddev:0.99 (1.6%) stddev-sans-first:1.05 22:37:37 INFO - PROCESS | 20886 | Values: 62.0 63.0 61.0 61.0 64.0 61.0 62.0 61.0 62.0 62.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#10] stanford-crypto-aes Cycles:10 Average:78.70 Median:78.50 stddev:2.67 (3.4%) stddev-sans-first:2.83 22:37:37 INFO - PROCESS | 20886 | Values: 79.0 78.0 77.0 77.0 82.0 76.0 77.0 76.0 83.0 82.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#11] stanford-crypto-ccm Cycles:10 Average:133.00 Median:133.00 stddev:12.84 (9.7%) stddev-sans-first:13.61 22:37:37 INFO - PROCESS | 20886 | Values: 132.0 121.0 123.0 139.0 134.0 137.0 129.0 122.0 165.0 128.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.10 Median:162.50 stddev:3.35 (2.1%) stddev-sans-first:2.45 22:37:37 INFO - PROCESS | 20886 | Values: 168.0 163.0 160.0 159.0 162.0 158.0 163.0 157.0 158.0 163.0 22:37:37 INFO - PROCESS | 20886 | 22:37:37 INFO - PROCESS | 20886 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:3.43 (5.2%) stddev-sans-first:3.54 22:37:37 INFO - PROCESS | 20886 | Values: 64.0 67.0 74.0 65.0 70.0 63.0 67.0 65.0 65.0 63.0 22:37:37 INFO - PROCESS | 20886 | -------- Summary: end -------- 22:37:37 INFO - PROCESS | 20886 | 22:37:38 INFO - TEST-INFO | 20886: exit 0 22:37:38 INFO - TEST-OK | kraken | took 61771ms 22:37:38 INFO - SUITE-END | took 1173s 22:37:38 INFO - Completed test suite (00:19:33) 22:37:38 INFO - javascript benchmark 22:37:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7263.472962164286, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3083.136721717919, 3084.2135255509843, 3105.596272484577, 3116.2365220520974, 3118.968810311896, 5421.945780542198, 5500.202470641754, 5501.862453438666, 5503.944960550393, 5523.696196709179, 5414.810481633143, 5423.376311724153, 5448.8365349039495, 5495.807646732355, 5555.2222688823595, 4935.55579997801, 4986.40163180417, 5098.872528186806, 5131.358580177478, 5139.974300128512, 5011.2483127530795, 5156.69059856408, 5157.871053223662, 5167.302414174094, 5168.870778230536, 4932.136876046678, 4933.1860243059855, 5030.622703297267, 5050.0, 5114.514121158484, 4915.115279249733, 4932.901341973176, 4940.802367905279, 4951.0, 5070.847874563732, 4943.9258411123865, 4954.43024052235, 4992.0, 4997.200447928314, 5038.697678139323, 5355.866103347408, 5432.70120143392, 5472.179173124024, 5481.561475081984, 5527.198556209368, 5861.736221870026, 5862.912056319158, 5864.1203819427, 5869.295684517842, 5906.8818623627285, 5862.648241105548, 5906.0, 5910.320313163955, 5910.497607703351, 5910.936031514326, 5370.194470829368, 5466.070767969455, 5494.835154945358, 5522.889542209134, 5547.029269877755, 6064.605800622945, 6071.544634152457, 6073.0, 6081.118237855575, 6107.206063211843, 5802.448767367177, 5824.3302020267365, 5825.38833422493, 5849.444302791228, 5869.8239052828485, 5807.390224026416, 5882.470577648032, 5919.052951527735, 5923.437273459014, 5927.673977931169, 5682.7442765075675, 5860.208871802337, 5884.911726324109, 5911.261092363455, 5971.790987315423, 5906.793262235887, 5916.763329466859, 5957.791477298274, 5975.970120149372, 6012.7294271757, 4413.691041627056, 4441.067375851043, 4462.821487140543, 4512.954870451254, 4521.0279789845345, 3941.0541470047556, 3987.222491614107, 4010.8997275068355, 4021.9195616087513, 4027.033511957168, 5539.390667026624, 5615.971920140373, 5654.943450565524, 5656.660600363992, 5664.68844213564, 5730.856728581819, 5900.64596124234, 5924.0, 5937.762489500372, 5938.198343223696, 5642.774289028475, 5724.055530837449, 5737.90979713853, 5748.683822389725, 5764.682942438206, 5151.356080489939, 5157.6647517911215, 5163.870903227375, 5164.586833053347, 5172.353455818023, 5946.583739138306, 5952.791652292149, 6148.9692551537855, 6167.321594624587, 6170.043643235305, 5855.473007429352, 5939.07944268639, 5959.821205363846, 5991.0, 6005.939940600538, 5989.760409583742, 6038.275406951194, 6068.7269072892705, 6071.058985857199, 6087.482563982111, 5636.605437619327, 5731.226284451713, 5798.797042103506, 5818.592698511064, 5820.0, 5870.853228669317, 5938.465538101592, 5943.257092863392, 5958.842321935, 6008.0, 5700.885982280414, 5770.307563092456, 5782.7686892523825, 5786.479216870502, 5786.9131963019745, 5664.603477756517, 5674.0, 5742.655440673573, 5774.682392468455, 5779.913301300568, 5646.463585959327, 5692.7722891083895, 5700.771969121189, 5728.312602487728, 5741.311042674905, 5767.7404516796005, 5787.681677507778, 5796.246487956707, 5825.271841019872, 5827.067669172912, 5535.723213839211, 5604.8038318658655, 5634.380218175916, 5638.23883775685, 5706.343770466449], "value": 5434.6211525291665, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [540.2708884350029, 581.2066529187657, 590.8700085981084, 602.6534742523049, 622.1072760588559, 39029.609703902985, 43015.0, 43031.35452968204, 43089.13821723564, 43215.0, 43373.13253734924, 43507.56492435075, 43766.0, 43797.7810110949, 43922.560774392325, 8662.047174810781, 8849.734507964771, 8850.159234872677, 8853.335999800009, 8853.95573022134, 8602.18279263469, 8726.258268047224, 8746.125387461234, 8751.912480875206, 8794.956025219897, 8743.0, 8803.77990550235, 8807.0, 8821.11788821116, 8823.161799629024, 8777.824443511126, 8783.077776833405, 8815.647374105029, 8816.603252853636, 8856.911430885737, 8721.12788721129, 8731.126887311282, 8773.517456539888, 8774.780630484289, 8777.7805554861, 40638.59361406378, 41018.38472422886, 41133.38299925504, 41254.1749165015, 41338.79330603328, 3617.963820361815, 3704.518412606344, 3762.9097440503137, 3766.322062028834, 4120.134771697947, 32645.878017928997, 32760.508592371134, 32869.67130328714, 32871.0, 32947.50578741321, 4326.134773045404, 4423.867283981485, 4462.553744625511, 4468.575485328888, 4599.83900563482, 335314.0, 336605.0, 339172.0, 339177.0, 341495.0, 57795.422045779844, 58233.84726138344, 58302.7084864573, 58302.70848645814, 58391.70804146037, 60759.35443873382, 61138.0, 61580.07629885555, 61775.38224617786, 61867.0, 15063.397464101314, 15065.698686026437, 15262.694746105015, 15266.923665381602, 15296.235188240544, 12378.0, 12417.565385211474, 12489.375531223403, 12512.436940337706, 12514.124011319302, 16482.2582983766, 17202.13989300555, 17468.126593670266, 17479.737803932952, 17570.472885813448, 5994.820155395345, 6002.279726432856, 6050.3949605040025, 6077.3314935357075, 6115.571909966348, 970.6408628807386, 1001.9398836069859, 1010.3382284603597, 1043.7495001199657, 1059.782744537368, 31963.840180799412, 32260.677393225767, 32386.83806580952, 32394.190145246084, 33010.0, 1098.3903933317085, 1109.877913429522, 1116.1238427834273, 1126.3692332293779, 1144.2962578014628, 16663.000659881094, 16790.412335568195, 16961.575960601083, 17102.315907363816, 17436.82563174352, 566059.0, 576971.0, 578229.0, 876654.0, 882312.0, 59553.40446595565, 59709.70145149333, 60198.699006505565, 60282.397176027676, 60422.395776042555, 1331.1148086522549, 1350.709597436556, 1359.4969861151437, 1362.1758835904334, 1381.4681347681214, 32165.678343216267, 33280.500792488616, 33380.833095834365, 33553.83223083869, 33757.0, 51046.0, 51395.0, 51398.74300628473, 51545.48454515407, 51600.48399516107, 430889.0, 434078.0, 435932.0, 437446.0, 437931.0, 27174.864125679243, 28040.57939130874, 28047.158585242476, 28092.85953570219, 29691.85154074216, 48178.0, 48843.75578122087, 48965.510344896094, 48967.75516122397, 49041.50958490512, 37564.44735004335, 42239.66405039269, 42862.78568607137, 43662.781686091366, 44107.77946110249, 42291.57708423, 44419.0, 44733.329000064376, 45372.31941520814, 45814.77092614515, 42866.357004644335, 43511.347329789445, 43571.0, 43759.562404376826, 44989.550104499845, 43670.0, 43715.90710232143, 43913.0, 44275.0, 44331.0, 28929.0, 29970.850145749133, 30084.69915300819, 30240.69759302379, 30486.847565762917], "value": 20319.06721450703, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.6798221511714, 381.862529489384, 396.7877185705647, 397.85876014015025, 414.48810718762326, 13748.0, 18507.629847403045, 18759.81240187601, 19987.20051197954, 20041.799582004176, 17889.552761180978, 18109.547261318472, 18417.26330946764, 19298.035098245065, 19348.90325548374, 5289.180177072541, 5454.699991500466, 5747.224124743158, 5950.494207992326, 5957.046872500401, 5488.53347465466, 5569.275994120779, 5579.107342825148, 5601.467860553241, 5609.49514543691, 5517.972410137943, 5540.722963851811, 5596.132599447093, 5602.467765562285, 5677.602567820254, 5269.420363759983, 5516.282883225195, 5668.206451096849, 5686.516646085088, 5691.08942569189, 5363.919541206885, 5435.0488664483555, 5449.3460784706085, 5460.290162278879, 5468.835934921959, 13141.802872956914, 13719.519816806363, 22197.445063873372, 23042.769572304398, 23074.307770766904, 2193.5722534105857, 2360.976390236093, 2491.6262560615874, 2529.1148098165677, 2854.9143525694262, 9266.258699304039, 9313.627454901864, 9377.718668439958, 9690.418574885529, 14247.572572822832, 1809.3215044358365, 1845.9538511537185, 1898.8196121368449, 1969.9901500492406, 1976.883061070505, 81104.18895811026, 83154.58422707848, 83939.0, 85244.0, 86467.56766216192, 10519.31624444424, 10609.575616975235, 10865.185111116698, 10874.347539147677, 10947.83578246327, 14970.775438368433, 15106.169160696047, 15108.0, 15132.621684457756, 15172.620684482976, 7749.4575379724, 8030.116687164407, 8037.437379383387, 8081.838363232702, 8170.264676179172, 7592.202818704067, 7696.884546731803, 7811.687532498637, 7887.9605601971625, 7909.564973926489, 4437.5784300491405, 4605.263157894721, 4647.442306923191, 4687.226607609706, 4717.268823332389, 1799.901005444687, 1847.9260829566936, 1939.6217737541044, 1942.6794578894323, 1972.2308299763104, 926.7219834049758, 959.2230293462318, 1002.1682003936716, 1014.9594016239415, 1034.6585626743308, 18948.0, 19381.0, 19444.0, 19462.0, 19787.604247914962, 963.6868017894212, 996.4619105682995, 1066.3495267886583, 1073.897979691928, 1079.5411949921252, 9870.506474676382, 10730.316782389626, 10764.1209169254, 10782.417189964597, 10829.65257271472, 459102.0, 474591.0, 501849.0, 506779.0, 509828.0, 90991.54504227436, 92419.0, 94563.52718236364, 96337.0, 97368.0, 987.511181964932, 1024.3495380433478, 1054.4095306628312, 1059.369675043348, 1061.4480470155477, 16611.41860034893, 16979.66040679204, 17734.556636084202, 17768.0, 18148.092595370443, 40485.39271910983, 41205.793971029954, 41831.954201145214, 42251.0, 42586.57413425826, 55803.0, 56438.1534276978, 59902.4058556488, 59910.80178396495, 61118.388816113045, 6940.9652951734915, 7979.601019948863, 8011.879821802561, 8070.192980702118, 8155.388345873965, 10062.69811905644, 12584.685382865502, 12728.1726687765, 12918.289494077915, 13056.151350162208, 11633.825492617449, 11790.351530665896, 11908.380156365585, 11971.0, 12008.879911201126, 10626.25616206889, 11442.141839362248, 11621.922763679162, 11704.824427633423, 11870.821937671115, 11772.234804737665, 11788.351640659846, 11815.0, 11846.822297665369, 12096.45565949552, 7217.819554511179, 7505.774826755206, 7545.622718863925, 7584.0, 7859.567723775247, 9719.271054670786, 11413.144014199135, 11556.826647600461, 11955.342456164994, 11965.282083075042], "value": 9058.667446231444, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1434.8206474190727, 1485.9479918202862, 1595.6649103688226, 1784.072282413146, 1791.390927084791, 7764.728234511793, 8660.870086948693, 8769.824603507926, 9268.58291376888, 9274.258059355252, 8838.160374764404, 9143.497107659075, 9145.131212534816, 9164.312676549269, 9184.632614695422, 2274.3404412720333, 2327.3483424641126, 2340.040583360814, 2347.307544274435, 2353.9058437662475, 2305.7694230576976, 2322.268485427085, 2328.359701082199, 2352.6118190498632, 2361.3860396297, 2299.8045166160814, 2305.250793492113, 2328.0338659456306, 2338.6024375856146, 2349.6710460535455, 2241.12596087526, 2249.932502024942, 2250.707408036945, 2261.694671219389, 2264.29806759905, 2164.199246278871, 2164.6211912915333, 2167.7398712154486, 2175.869447833135, 2177.5318306564163, 9151.633934642672, 9204.861927071099, 9205.585748641328, 9230.953845230732, 9258.444493330422, 5106.974465127651, 5226.608004399647, 5260.815871444481, 5270.209468579706, 5274.578033757329, 3263.8857639982725, 3313.8177400242976, 3416.9487457688156, 3441.6558344165383, 3450.344434557426, 1118.8545489086366, 1128.2722643894642, 1130.8473356096986, 1138.1463902073444, 1165.8775828537966, 12762.936185319015, 12777.0, 12812.359382030954, 12834.80747788784, 12854.807177892339, 4390.868273951786, 4467.396901418332, 4581.862544123682, 4627.814887404466, 4685.625149987993, 10699.251052426256, 10744.570217191225, 10802.51388687511, 10811.972862578043, 10833.729156771144, 5243.528082472596, 5254.133068043807, 5254.632175747737, 5258.0, 5261.0, 4341.869743907688, 4615.215413379734, 4709.199436095803, 4723.504032076651, 4795.664303498791, 3930.3514920038447, 4043.7978101094827, 4111.527174374925, 4156.085661154541, 4166.562510936369, 1285.1068507387276, 1320.6764342736092, 1327.4424741608548, 1328.926909019994, 1337.5987203839004, 829.5852073963018, 845.7293666026812, 932.1843387036344, 948.170350942925, 963.1379914976197, 5922.674252916045, 5993.160957465958, 6039.758409663652, 6075.5747097703625, 6156.583985683318, 390.9706771992112, 393.5631449091506, 396.41330830371214, 411.06482751739435, 416.17389481878445, 4197.118605092904, 4211.642010429148, 4222.092250166229, 4238.724482908663, 4285.271503844354, 11006.724831879266, 11315.0, 11441.771164576661, 11446.08431325492, 11465.885341146482, 10697.197710171768, 11087.667369978913, 11295.152863535268, 11317.49071291794, 11352.829707554394, 8267.338612910953, 8578.571071446402, 8586.356023298153, 8587.570621468902, 8623.051464338918, 5794.797182098607, 5798.6810725410505, 5990.161377407254, 6013.248343957005, 6131.662758548323, 10025.849612255968, 10226.0, 10267.127294180078, 10293.794124117327, 10319.174466042849, 10576.153907687212, 10794.298370605886, 10806.459677016275, 10812.135029197803, 10823.404712740874, 7127.821804454723, 7698.384129269757, 7769.067711874612, 7884.132745398117, 8050.879236811335, 8677.696280630147, 8806.60370283326, 8815.471071735716, 8843.51360675169, 8882.422642528216, 7655.425843061681, 7849.529028258323, 7943.721969731032, 7973.880391794244, 8070.152633973583, 7434.888476672746, 7750.806229844299, 7812.335951444191, 7879.803004924694, 7902.802429939297, 7012.894806577803, 7073.6463176839925, 7124.821879453055, 7125.501214914906, 7131.607761573163, 5157.484251574812, 5170.560502357342, 5266.0, 5282.418933917189, 5441.2654291670115, 5128.743562821918, 5152.458991805806, 5168.457311982188, 5250.159974404077, 5457.0], "value": 4585.672365332233, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.695330762622, 317.64423845293277, 319.0794557701032, 323.32425231266654, 326.9297101123259, 5402.540784033362, 5565.526930210932, 5575.8884822303635, 5638.407967163445, 5699.629524080932, 5537.335519737627, 5559.9444005559935, 5560.388357280696, 5564.0, 5581.134924086763, 4279.3367028110615, 4287.957120428802, 4289.549597292286, 4290.313549832027, 4292.484901811787, 4120.855770048049, 4240.97879510602, 4259.616634502894, 4263.722858014219, 4280.507741609724, 4210.347396153601, 4224.064275328647, 4227.0, 4229.429027081351, 4244.72658202538, 4186.979065104655, 4194.958050419503, 4220.240356735786, 4231.513375961757, 4262.1688770689725, 3621.0, 3703.370427027412, 3713.610070942539, 3721.5162028936065, 3738.9904725723904, 5435.918461223085, 5467.664724518041, 5469.589780766419, 5474.562035037187, 5478.411260734382, 4853.830115945925, 4858.975705121487, 4863.440704319013, 4885.951140488585, 4889.975550122226, 5553.583481238923, 5766.538676905838, 5807.216025836543, 5843.883122337529, 5850.619709718897, 4040.4141399496907, 4070.6743460523385, 4071.531773846016, 4073.1039171382545, 4077.7553346799286, 6010.128531362928, 6039.516838652898, 6044.455998960071, 6057.636541807506, 6067.271927368744, 4063.167050754588, 4122.896927576835, 4159.396887451303, 4165.896222407665, 4166.854160104443, 4187.895302617459, 4193.517745459311, 4196.0, 4197.118605092965, 4224.704270701083, 4815.7351345676325, 4836.613070954386, 4844.128056949712, 4851.539103785135, 4865.440474345425, 5738.737477754888, 5889.263842019747, 5891.558133140032, 5893.027650437715, 5920.171176035358, 1459.292243262017, 1489.054450423967, 1493.701259748033, 1493.7161939231512, 1499.3327969053666, 885.3536918049731, 924.4591913730409, 927.5037854747812, 930.6789157740569, 937.5968333616611, 4965.428975667773, 5005.874853128701, 5023.572996295283, 5024.84925452237, 5075.046107248736, 5274.0, 5304.939012197576, 5308.708021058802, 5321.388040375329, 5330.466953304638, 4697.647676424282, 4992.72540010303, 5025.7989680412375, 5064.772085256172, 5071.290019397287, 2778.6387769589824, 2787.846668433215, 2794.3712664650293, 2811.536096544088, 2824.5904343870025, 5392.842862854811, 5397.0, 5408.45915408456, 5422.674639521641, 5441.564674826004, 5083.288339632454, 5237.6333656643665, 5292.894142117136, 5325.680459172462, 5358.089124848785, 5316.255724198615, 5353.678779273178, 5358.27663265462, 5371.758270877898, 5405.243265942771, 5206.4901178657765, 5226.608004399609, 5243.659162154448, 5255.710935898409, 5264.499872512105, 4887.511248875227, 5157.7679004444135, 5172.948270517397, 5188.0, 5208.765605547836, 5306.761195746278, 5310.707911064928, 5325.573954083741, 5330.747274390437, 5348.747745542381, 3774.50931378919, 3996.1807829394324, 4130.194612050621, 4247.853079668534, 4249.532551419403, 5900.232969714081, 5952.732127054206, 5964.522838172849, 5974.790882319098, 5982.88034239304, 5607.009511623315, 5799.594028418139, 5815.738291776965, 5824.563157763269, 5824.854378640568, 5734.598578099493, 5750.856228594319, 5753.942460575341, 5775.94224057754, 5793.797217097382, 5654.660720356792, 5666.0, 5697.259356283656, 5725.770969161187, 5741.282339707536, 5609.23309157614, 5759.827205183851, 5762.0, 5779.219805326227, 5794.319647302235, 5623.325200975909, 5665.943340566541, 5667.234923285304, 5670.9432905672065, 5698.1737648041635], "value": 4233.68861910262, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.9229380915001, 747.3460721868365, 751.8909758085077, 784.6861255497801, 849.7378573548323, 15471.767923481142, 16751.246193921274, 16786.580335491617, 16822.411215607455, 16857.662846743075, 15182.089074655529, 15415.152166630858, 15488.535343939671, 15491.380344786196, 15491.767623485657, 667.4927055437854, 793.3415265329792, 799.6641410607559, 816.6447595296036, 822.9753107406788, 815.4862436664893, 820.0446479850966, 820.9179082091803, 827.5076329583887, 829.3945419843487, 880.0, 885.5926273914007, 886.3573908916048, 889.3196704521014, 889.6308032166643, 884.3897710579712, 885.5085427587686, 885.8494056010494, 886.1006078829993, 886.2068448738343, 641.7240586547782, 644.7549931026227, 648.5492582655056, 652.1228947066188, 652.1456891472187, 14084.788728169086, 14086.013979021473, 14098.295085245696, 14115.929420352833, 14167.07913985598, 14957.775633365509, 15082.321295541728, 15108.0, 15130.470433534883, 15336.0, 11925.701857453547, 11952.880471195263, 11981.700457488545, 12392.690182745413, 12432.378381081, 3987.8803635890968, 4019.7588144711412, 4036.576159503239, 4107.301758701028, 4202.327627579603, 67846.66076669584, 69846.0, 70255.29744702538, 78181.0, 78338.0, 11570.42147892602, 12059.457324420422, 12170.452329645188, 12205.572804951784, 12213.0, 21626.891865540885, 21637.86250993597, 21649.025793839315, 21833.563328733337, 21850.672239916414, 8072.273495385443, 8172.6730930762105, 8250.437976970652, 8721.651133954698, 8832.646694132163, 4635.2120139576355, 4672.439307283148, 4674.275487299474, 4712.858614241578, 4813.590844778163, 3615.6745892869244, 3728.067983004249, 3745.7190710696805, 3751.2685026419585, 3762.7930463824205, 644.6196743921015, 649.3441623959834, 660.7918505670698, 661.1141070964931, 667.8998150277497, 614.2720875762236, 641.4547634510628, 648.670226036623, 650.5511197273865, 679.3104998426601, 9664.323497355259, 9881.703548893545, 9897.752556186153, 9935.205183585296, 9949.502524873873, 532.1868809976552, 544.6595877576515, 604.6613896218132, 626.5175814622806, 743.5129989856653, 4495.977520112378, 4510.977445112819, 4538.909221815546, 4583.613828059988, 4639.768011599474, 65666.34333656698, 109081.90918090717, 117722.41138794423, 118448.40775796065, 121467.0, 56548.71725641345, 57865.0, 69831.0, 90097.0, 100042.0, 84781.15218847856, 85173.57413212894, 85409.57295213484, 85855.57072214599, 92266.53866730623, 8575.228229459379, 8695.260902823331, 8827.426217295855, 8841.0, 8975.102489750972, 53139.20291195558, 53306.73346633397, 53705.731471343955, 54516.0, 55051.44948550463, 69353.0, 69489.0, 78504.21495785197, 79104.0, 79810.60094699722, 11359.432028398713, 11685.824712629488, 11704.403236984956, 11852.76294474123, 11930.642080737593, 11923.880761192624, 12084.879151208726, 15177.317020733874, 15438.073715577102, 15853.52439426819, 14935.85064149388, 15009.2495375233, 15070.321835517647, 15100.54698359051, 15216.543503694907, 14745.483908063166, 14843.257837107885, 15015.849841501446, 15045.699086018438, 15279.236038197823, 13037.217766933634, 14271.0, 14423.42306307736, 14527.636809079857, 14760.188189649672, 11316.0, 11883.821742673694, 11897.940510297392, 11951.402429878297, 12554.183978041397, 11533.596324128615, 12210.206336588093, 12314.876851231731, 12488.313142776871, 12494.750104997667], "value": 7560.690421049704, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 112.0, 107.0, 112.0, 113.0, 113.0, 113.0, 113.0, 114.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 122.0, 145.0, 179.0, 119.0, 152.0, 151.0, 129.0, 185.0, 118.0], "value": 144.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 163.0, 162.0, 162.0, 163.0, 176.0, 164.0, 162.0, 162.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 74.0, 143.0, 76.0, 74.0, 75.0, 74.0, 180.0, 76.0], "value": 92.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 149.0, 84.0, 85.0, 84.0, 133.0, 84.0, 85.0, 139.0], "value": 101.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 118.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 147.0, 148.0, 148.0, 146.0, 147.0, 148.0, 147.0, 147.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 100.0, 96.0, 96.0, 95.0, 96.0, 97.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 56.0, 56.0, 59.0, 53.0, 59.0, 54.0, 53.0, 52.0], "value": 55.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 61.0, 61.0, 64.0, 61.0, 62.0, 61.0, 62.0, 62.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 77.0, 77.0, 82.0, 76.0, 77.0, 76.0, 83.0, 82.0], "value": 78.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 121.0, 123.0, 139.0, 134.0, 137.0, 129.0, 122.0, 165.0, 128.0], "value": 133.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 163.0, 160.0, 159.0, 162.0, 158.0, 163.0, 157.0, 158.0, 163.0], "value": 161.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 74.0, 65.0, 70.0, 63.0, 67.0, 65.0, 65.0, 63.0], "value": 66.3, "unit": "score"}]}]} 22:37:38 INFO - Return code: 0 22:37:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:37:38 ERROR - # TBPL SUCCESS # 22:37:38 INFO - Running post-action listener: _resource_record_post_action 22:37:38 INFO - [mozharness: 2016-07-23 05:37:38.321956Z] Finished run-tests step (success) 22:37:38 INFO - Running post-run listener: _resource_record_post_run 22:37:38 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 554938368; Read time: 12; Write time: 519996 22:37:38 INFO - TinderboxPrint: CPU usage
13.4% 22:37:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 22:37:38 INFO - TinderboxPrint: I/O write bytes / time
554,938,368 / 519,996 22:37:38 INFO - TinderboxPrint: CPU idle
8,232.7 (86.6%) 22:37:38 INFO - TinderboxPrint: CPU user
1,211.8 (12.7%) 22:37:38 INFO - TinderboxPrint: Swap in / out
0 / 0 22:37:38 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 257495040; Read time: 0; Write time: 281704 22:37:38 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 297443328; Read time: 0; Write time: 238292 22:37:38 INFO - Running post-run listener: _upload_blobber_files 22:37:38 INFO - Blob upload gear active. 22:37:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:37:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:37:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:37:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:37:39 INFO - (blobuploader) - INFO - Open directory for files ... 22:37:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:37:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:37:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:40 INFO - (blobuploader) - INFO - Done attempting. 22:37:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:37:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:37:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:41 INFO - (blobuploader) - INFO - Done attempting. 22:37:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:37:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:37:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:37:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:37:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:37:44 INFO - (blobuploader) - INFO - Done attempting. 22:37:44 INFO - (blobuploader) - INFO - Iteration through files over. 22:37:44 INFO - Return code: 0 22:37:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:37:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:37:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/93758586ccb672261d418104fdf45fa77fec8c46f6d205c9a7b330ae57f7e7d03d881a5134f779911d18488ee22618da931aa92d4c6c385ad14aaa757b56dba1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ce8d8ddab6980b21d5d21a72a81dce910364bf150cb8c7473552361ec96166bd69dc202e4cc5290638fb4dfcf643619f7ee2fe1a853f717b396f951a6b92c2c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e84dabdf6b2c4c33557f87cab65ac687d715876214015e1e2b7e7c0ad1a00e077e758454f4f8e769b83feef1673a4ee4f0933840102dc441727d05b94e4d639"} 22:37:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:37:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:37:44 INFO - Contents: 22:37:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/93758586ccb672261d418104fdf45fa77fec8c46f6d205c9a7b330ae57f7e7d03d881a5134f779911d18488ee22618da931aa92d4c6c385ad14aaa757b56dba1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ce8d8ddab6980b21d5d21a72a81dce910364bf150cb8c7473552361ec96166bd69dc202e4cc5290638fb4dfcf643619f7ee2fe1a853f717b396f951a6b92c2c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e84dabdf6b2c4c33557f87cab65ac687d715876214015e1e2b7e7c0ad1a00e077e758454f4f8e769b83feef1673a4ee4f0933840102dc441727d05b94e4d639"} 22:37:44 INFO - Running post-run listener: copy_logs_to_upload_dir 22:37:44 INFO - Copying logs to upload dir... 22:37:44 INFO - mkdir: /builds/slave/test/build/upload/logs 22:37:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.662161 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-07-22 22:37:44.498064) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 22:37:44.501931) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/93758586ccb672261d418104fdf45fa77fec8c46f6d205c9a7b330ae57f7e7d03d881a5134f779911d18488ee22618da931aa92d4c6c385ad14aaa757b56dba1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ce8d8ddab6980b21d5d21a72a81dce910364bf150cb8c7473552361ec96166bd69dc202e4cc5290638fb4dfcf643619f7ee2fe1a853f717b396f951a6b92c2c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e84dabdf6b2c4c33557f87cab65ac687d715876214015e1e2b7e7c0ad1a00e077e758454f4f8e769b83feef1673a4ee4f0933840102dc441727d05b94e4d639"} build_url:https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011406 build_url: 'https://queue.taskcluster.net/v1/task/BaGQFZrWQdOt2gqfSyN-nQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/93758586ccb672261d418104fdf45fa77fec8c46f6d205c9a7b330ae57f7e7d03d881a5134f779911d18488ee22618da931aa92d4c6c385ad14aaa757b56dba1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/ce8d8ddab6980b21d5d21a72a81dce910364bf150cb8c7473552361ec96166bd69dc202e4cc5290638fb4dfcf643619f7ee2fe1a853f717b396f951a6b92c2c9", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7e84dabdf6b2c4c33557f87cab65ac687d715876214015e1e2b7e7c0ad1a00e077e758454f4f8e769b83feef1673a4ee4f0933840102dc441727d05b94e4d639"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-22 22:37:44.528366) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 22:37:44.528735) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1469249902.68975-229610161 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-22 22:37:44.613616) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 22:37:44.613970) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-22 22:37:44.614298) ========= ========= Total master_lag: 0.19 =========