builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-068 starttime: 1466080839.59 results: success (0) buildid: 20160616051126 builduid: e113fd4e0e884f0dab216dccb788ba94 revision: 61b7d2351dd2ed50789df8aed472ee0af1e06829 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:39.587831) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:39.590116) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:39.590408) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:39.614701) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:39.614991) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-16 05:40:41-- 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% 77.8M=0s 2016-06-16 05:40:42 (77.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.160361 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:39.790986) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:39.791337) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018261 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:39.841829) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:39.842204) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 61b7d2351dd2ed50789df8aed472ee0af1e06829 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 61b7d2351dd2ed50789df8aed472ee0af1e06829 --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=97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-16 05:40:42,250 truncating revision to first 12 chars 2016-06-16 05:40:42,250 Setting DEBUG logging. 2016-06-16 05:40:42,250 attempt 1/10 2016-06-16 05:40:42,250 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/61b7d2351dd2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-16 05:40:42,565 unpacking tar archive at: fx-team-61b7d2351dd2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.657037 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:40.514030) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:40.514486) ========= script_repo_revision: 61b7d2351dd2ed50789df8aed472ee0af1e06829 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:40.515088) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:40.515531) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-16 05:40:40.527712) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-06-16 05:40:40.528158) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:40:43 INFO - MultiFileLogger online at 20160616 05:40:43 in /builds/slave/test 05:40:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 05:40:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:40:43 INFO - {'append_to_log': False, 05:40:43 INFO - 'base_work_dir': '/builds/slave/test', 05:40:43 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:40:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:40:43 INFO - 'branch': 'Fx-Team-Non-PGO', 05:40:43 INFO - 'buildbot_json_path': 'buildprops.json', 05:40:43 INFO - 'config_files': ('talos/linux_config.py',), 05:40:43 INFO - 'default_actions': ('clobber', 05:40:43 INFO - 'read-buildbot-config', 05:40:43 INFO - 'download-and-extract', 05:40:43 INFO - 'populate-webroot', 05:40:43 INFO - 'create-virtualenv', 05:40:43 INFO - 'install', 05:40:43 INFO - 'run-tests'), 05:40:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:40:43 INFO - 'download_minidump_stackwalk': True, 05:40:43 INFO - 'download_symbols': 'ondemand', 05:40:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:40:43 INFO - 'tooltool.py': '/tools/tooltool.py', 05:40:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:40:43 INFO - '/tools/misc-python/virtualenv.py')}, 05:40:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:40:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:40:43 INFO - 'installer_path': 'installer.exe', 05:40:43 INFO - 'log_level': 'info', 05:40:43 INFO - 'log_name': 'talos', 05:40:43 INFO - 'log_to_console': True, 05:40:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:40:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:40:43 INFO - 'opt_config_files': (), 05:40:43 INFO - 'pip_index': False, 05:40:43 INFO - 'sps_profile': False, 05:40:43 INFO - 'sps_profile_interval': 0, 05:40:43 INFO - 'suite': 'dromaeojs-e10s', 05:40:43 INFO - 'system_bits': '32', 05:40:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:40:43 INFO - 'title': 'talos-linux64-ix-068', 05:40:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:40:43 INFO - 'use_talos_json': True, 05:40:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:40:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:40:43 INFO - 'work_dir': 'build'} 05:40:43 INFO - ##### 05:40:43 INFO - ##### Running clobber step. 05:40:43 INFO - ##### 05:40:43 INFO - Running pre-action listener: _resource_record_pre_action 05:40:43 INFO - Running main action method: clobber 05:40:43 INFO - rmtree: /builds/slave/test/build 05:40:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:40:44 INFO - Running post-action listener: _resource_record_post_action 05:40:44 INFO - ##### 05:40:44 INFO - ##### Finished clobber step (success) 05:40:44 INFO - ##### 05:40:44 INFO - ##### 05:40:44 INFO - ##### Running read-buildbot-config step. 05:40:44 INFO - ##### 05:40:44 INFO - Running pre-action listener: _resource_record_pre_action 05:40:44 INFO - Running main action method: read_buildbot_config 05:40:44 INFO - Using buildbot properties: 05:40:44 INFO - { 05:40:44 INFO - "project": "", 05:40:44 INFO - "product": "firefox", 05:40:44 INFO - "script_repo_revision": "production", 05:40:44 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 05:40:44 INFO - "repository": "", 05:40:44 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 05:40:44 INFO - "buildid": "20160616051126", 05:40:44 INFO - "pgo_build": "False", 05:40:44 INFO - "basedir": "/builds/slave/test", 05:40:44 INFO - "buildnumber": 1591, 05:40:44 INFO - "slavename": "talos-linux64-ix-068", 05:40:44 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:40:44 INFO - "platform": "ubuntu64_hw", 05:40:44 INFO - "scheduler": "tests-fx-team-linux64-talos", 05:40:44 INFO - "branch": "fx-team", 05:40:44 INFO - "repo_path": "integration/fx-team", 05:40:44 INFO - "revision": "61b7d2351dd2ed50789df8aed472ee0af1e06829", 05:40:44 INFO - "stage_platform": "linux64", 05:40:44 INFO - "builduid": "e113fd4e0e884f0dab216dccb788ba94", 05:40:44 INFO - "slavebuilddir": "test" 05:40:44 INFO - } 05:40:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 05:40:44 INFO - Running post-action listener: _resource_record_post_action 05:40:44 INFO - ##### 05:40:44 INFO - ##### Finished read-buildbot-config step (success) 05:40:44 INFO - ##### 05:40:44 INFO - ##### 05:40:44 INFO - ##### Running download-and-extract step. 05:40:44 INFO - ##### 05:40:44 INFO - Running pre-action listener: _resource_record_pre_action 05:40:44 INFO - Running main action method: download_and_extract 05:40:44 INFO - mkdir: /builds/slave/test/build/tests 05:40:44 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')]} 05:40:44 INFO - https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:40:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:40:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:40:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/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 05:40:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/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 05:40:44 INFO - Downloaded 1361 bytes. 05:40:44 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 05:40:44 INFO - Using the following test package requirements: 05:40:44 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:40:44 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:40:44 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:40:44 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:40:44 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:40:44 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:40:44 INFO - u'jsshell-linux-x86_64.zip'], 05:40:44 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:40:44 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:40:44 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 05:40:44 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:40:44 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:40:44 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:40:44 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:40:44 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:40:44 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:40:44 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 05:40:44 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:40:44 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:40:44 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')]} 05:40:44 INFO - https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:40:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:40:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:40:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/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 05:40:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/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 05:40:45 INFO - Downloaded 22654315 bytes. 05:40:45 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'] 05:40:45 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 05:40:47 INFO - Return code: 0 05:40:47 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 05:40:47 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')]} 05:40:47 INFO - https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:40:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:40:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 05:40:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/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 05:40:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/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 05:40:48 INFO - Downloaded 22654315 bytes. 05:40:48 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'] 05:40:48 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 05:40:50 INFO - Return code: 0 05:40:50 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')]} 05:40:50 INFO - https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:40:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:40:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 05:40:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/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 05:40:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/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 05:40:51 INFO - Downloaded 14405384 bytes. 05:40:51 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'] 05:40:51 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 05:40:51 INFO - Return code: 0 05:40:51 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')]} 05:40:51 INFO - https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:40:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:40:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:40:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/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 05:40:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PP_y5hEoQ52kEEqMilp7rA/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 05:40:52 INFO - Downloaded 55443727 bytes. 05:40:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:40:52 INFO - mkdir: /builds/slave/test/properties 05:40:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:40:52 INFO - Writing to file /builds/slave/test/properties/build_url 05:40:52 INFO - Contents: 05:40:52 INFO - build_url:https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 05:40:53 INFO - Running post-action listener: _resource_record_post_action 05:40:53 INFO - Running post-action listener: set_extra_try_arguments 05:40:53 INFO - ##### 05:40:53 INFO - ##### Finished download-and-extract step (success) 05:40:53 INFO - ##### 05:40:53 INFO - ##### 05:40:53 INFO - ##### Running populate-webroot step. 05:40:53 INFO - ##### 05:40:53 INFO - Running pre-action listener: _resource_record_pre_action 05:40:53 INFO - Running main action method: populate_webroot 05:40:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:40:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:40:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:40:53 INFO - '--tppagecycles', 05:40:53 INFO - '7'], 05:40:53 INFO - 'tests': ['tsvgm']}}, 05:40:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:40:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:40:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:40:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:40:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:40:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:40:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:53 INFO - 'talos_options': ['--disable-e10s'], 05:40:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:40:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:53 INFO - 'talos_options': ['--disable-e10s'], 05:40:53 INFO - 'tests': ['damp', 'tps']}, 05:40:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:53 INFO - 'tests': ['damp', 'tps']}, 05:40:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:40:53 INFO - 'tests': ['dromaeo_dom']}, 05:40:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:40:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:40:53 INFO - 'tests': ['basic_compositor_video']}, 05:40:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:40:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:40:53 INFO - 'tests': ['a11yr', 05:40:53 INFO - 'ts_paint', 05:40:53 INFO - 'tpaint', 05:40:53 INFO - 'sessionrestore', 05:40:53 INFO - 'sessionrestore_no_auto_restore', 05:40:53 INFO - 'tabpaint']}, 05:40:53 INFO - 'other-e10s': {'tests': ['a11yr', 05:40:53 INFO - 'ts_paint', 05:40:53 INFO - 'tpaint', 05:40:53 INFO - 'sessionrestore', 05:40:53 INFO - 'sessionrestore_no_auto_restore', 05:40:53 INFO - 'tabpaint']}, 05:40:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:40:53 INFO - 'ts_paint', 05:40:53 INFO - 'tpaint', 05:40:53 INFO - 'sessionrestore', 05:40:53 INFO - 'sessionrestore_no_auto_restore', 05:40:53 INFO - 'tabpaint']}, 05:40:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:40:53 INFO - 'ts_paint', 05:40:53 INFO - 'tpaint', 05:40:53 INFO - 'sessionrestore', 05:40:53 INFO - 'sessionrestore_no_auto_restore', 05:40:53 INFO - 'tabpaint']}, 05:40:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:40:53 INFO - 'tests': ['a11yr', 05:40:53 INFO - 'ts_paint', 05:40:53 INFO - 'tpaint', 05:40:53 INFO - 'sessionrestore', 05:40:53 INFO - 'sessionrestore_no_auto_restore', 05:40:53 INFO - 'tabpaint']}, 05:40:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:40:53 INFO - 'tests': ['a11yr', 05:40:53 INFO - 'ts_paint', 05:40:53 INFO - 'tpaint', 05:40:53 INFO - 'sessionrestore', 05:40:53 INFO - 'sessionrestore_no_auto_restore', 05:40:53 INFO - 'tabpaint']}, 05:40:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:40:53 INFO - 'tests': ['tsvgx', 05:40:53 INFO - 'tsvgr_opacity', 05:40:53 INFO - 'tart', 05:40:53 INFO - 'tscrollx', 05:40:53 INFO - 'cart']}, 05:40:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:40:53 INFO - 'tsvgr_opacity', 05:40:53 INFO - 'tart', 05:40:53 INFO - 'tscrollx', 05:40:53 INFO - 'cart']}, 05:40:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:53 INFO - 'talos_options': ['--disable-e10s'], 05:40:53 INFO - 'tests': ['tp5o']}, 05:40:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:53 INFO - 'tests': ['tp5o']}, 05:40:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:53 INFO - 'talos_options': ['--disable-e10s', 05:40:53 INFO - '--xperf_path', 05:40:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:53 INFO - 'tests': ['tp5n']}, 05:40:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:40:53 INFO - 'talos_options': ['--xperf_path', 05:40:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:40:53 INFO - 'tests': ['tp5n']}}, 05:40:53 INFO - 'talos.zip': {'path': '', 05:40:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:40:53 INFO - Running post-action listener: _resource_record_post_action 05:40:53 INFO - ##### 05:40:53 INFO - ##### Finished populate-webroot step (success) 05:40:53 INFO - ##### 05:40:53 INFO - ##### 05:40:53 INFO - ##### Running create-virtualenv step. 05:40:53 INFO - ##### 05:40:53 INFO - Running pre-action listener: _resource_record_pre_action 05:40:53 INFO - Running main action method: create_virtualenv 05:40:53 INFO - Creating virtualenv /builds/slave/test/build/venv 05:40:53 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 05:40:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:40:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:40:53 INFO - Using real prefix '/usr' 05:40:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:40:54 INFO - Installing distribute.............................................................................................................................................................................................done. 05:40:56 INFO - Installing pip.................done. 05:40:56 INFO - Return code: 0 05:40:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:40:56 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')]} 05:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:56 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')]} 05:40:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:56 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 0x7ff22de7df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a6350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28351c0>, '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 0x28360c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2836550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', '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 05:40:56 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 05:40:56 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 05:40:56 INFO - Using env: {'DISPLAY': ':0', 05:40:56 INFO - 'HOME': '/home/cltbld', 05:40:56 INFO - 'LANG': 'en_US.UTF-8', 05:40:56 INFO - 'LANGUAGE': 'en_US:en', 05:40:56 INFO - 'LOGNAME': 'cltbld', 05:40:56 INFO - 'MAIL': '/var/mail/cltbld', 05:40:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:56 INFO - 'MOZ_NO_REMOTE': '1', 05:40:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:56 INFO - 'NO_EM_RESTART': '1', 05:40:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:56 INFO - 'PWD': '/builds/slave/test', 05:40:56 INFO - 'SHELL': '/bin/bash', 05:40:56 INFO - 'SHLVL': '1', 05:40:56 INFO - 'TERM': 'linux', 05:40:56 INFO - 'TMOUT': '86400', 05:40:56 INFO - 'USER': 'cltbld', 05:40:56 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', 05:40:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:56 INFO - '_': '/tools/buildbot/bin/python'} 05:40:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:56 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 05:40:56 INFO - Cleaning up... 05:40:56 INFO - Return code: 0 05:40:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:40:56 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')]} 05:40:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:56 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')]} 05:40:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:56 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 0x7ff22de7df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a6350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28351c0>, '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 0x28360c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2836550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', '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 05:40:56 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 05:40:56 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 05:40:56 INFO - Using env: {'DISPLAY': ':0', 05:40:56 INFO - 'HOME': '/home/cltbld', 05:40:56 INFO - 'LANG': 'en_US.UTF-8', 05:40:56 INFO - 'LANGUAGE': 'en_US:en', 05:40:56 INFO - 'LOGNAME': 'cltbld', 05:40:56 INFO - 'MAIL': '/var/mail/cltbld', 05:40:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:56 INFO - 'MOZ_NO_REMOTE': '1', 05:40:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:56 INFO - 'NO_EM_RESTART': '1', 05:40:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:56 INFO - 'PWD': '/builds/slave/test', 05:40:56 INFO - 'SHELL': '/bin/bash', 05:40:56 INFO - 'SHLVL': '1', 05:40:56 INFO - 'TERM': 'linux', 05:40:56 INFO - 'TMOUT': '86400', 05:40:56 INFO - 'USER': 'cltbld', 05:40:56 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', 05:40:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:56 INFO - '_': '/tools/buildbot/bin/python'} 05:40:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:56 INFO - Downloading/unpacking psutil>=3.1.1 05:40:56 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 05:40:56 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 05:40:56 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 05:40:56 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 05:40:56 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 05:40:56 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 05:40:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:40:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:58 INFO - Installing collected packages: psutil 05:40:58 INFO - Running setup.py install for psutil 05:40:58 INFO - building 'psutil._psutil_linux' extension 05:40:58 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 05:40:58 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 05:40:58 INFO - building 'psutil._psutil_posix' extension 05:40:58 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 05:40:59 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 05:40:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:40:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:40:59 INFO - Successfully installed psutil 05:40:59 INFO - Cleaning up... 05:40:59 INFO - Return code: 0 05:40:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:40: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')]} 05:40:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:40:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40: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')]} 05:40:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:40:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:40:59 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 0x7ff22de7df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a6350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28351c0>, '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 0x28360c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2836550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', '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 05:40:59 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 05:40:59 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 05:40:59 INFO - Using env: {'DISPLAY': ':0', 05:40:59 INFO - 'HOME': '/home/cltbld', 05:40:59 INFO - 'LANG': 'en_US.UTF-8', 05:40:59 INFO - 'LANGUAGE': 'en_US:en', 05:40:59 INFO - 'LOGNAME': 'cltbld', 05:40:59 INFO - 'MAIL': '/var/mail/cltbld', 05:40:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:40:59 INFO - 'MOZ_NO_REMOTE': '1', 05:40:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:40:59 INFO - 'NO_EM_RESTART': '1', 05:40:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:40:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:40:59 INFO - 'PWD': '/builds/slave/test', 05:40:59 INFO - 'SHELL': '/bin/bash', 05:40:59 INFO - 'SHLVL': '1', 05:40:59 INFO - 'TERM': 'linux', 05:40:59 INFO - 'TMOUT': '86400', 05:40:59 INFO - 'USER': 'cltbld', 05:40:59 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', 05:40:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:40:59 INFO - '_': '/tools/buildbot/bin/python'} 05:40:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:40:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:40:59 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 05:40:59 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 05:40:59 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 05:40:59 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 05:40:59 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 05:40:59 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 05:41:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:41:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:41:01 INFO - Installing collected packages: mozsystemmonitor 05:41:01 INFO - Running setup.py install for mozsystemmonitor 05:41:01 INFO - Successfully installed mozsystemmonitor 05:41:01 INFO - Cleaning up... 05:41:01 INFO - Return code: 0 05:41:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:41:01 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')]} 05:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:01 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')]} 05:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:01 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 0x7ff22de7df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a6350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28351c0>, '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 0x28360c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2836550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', '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 05:41:01 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 05:41:01 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 05:41:01 INFO - Using env: {'DISPLAY': ':0', 05:41:01 INFO - 'HOME': '/home/cltbld', 05:41:01 INFO - 'LANG': 'en_US.UTF-8', 05:41:01 INFO - 'LANGUAGE': 'en_US:en', 05:41:01 INFO - 'LOGNAME': 'cltbld', 05:41:01 INFO - 'MAIL': '/var/mail/cltbld', 05:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:01 INFO - 'MOZ_NO_REMOTE': '1', 05:41:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:01 INFO - 'NO_EM_RESTART': '1', 05:41:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:01 INFO - 'PWD': '/builds/slave/test', 05:41:01 INFO - 'SHELL': '/bin/bash', 05:41:01 INFO - 'SHLVL': '1', 05:41:01 INFO - 'TERM': 'linux', 05:41:01 INFO - 'TMOUT': '86400', 05:41:01 INFO - 'USER': 'cltbld', 05:41:01 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', 05:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:01 INFO - '_': '/tools/buildbot/bin/python'} 05:41:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:01 INFO - Downloading/unpacking jsonschema==2.5.1 05:41:01 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 05:41:01 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 05:41:01 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 05:41:01 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 05:41:01 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 05:41:01 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 05:41:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:41:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:41:03 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 05:41:03 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 05:41:03 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 05:41:03 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 05:41:03 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 05:41:03 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 05:41:03 INFO - Downloading functools32-3.2.3-2.tar.gz 05:41:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:41:03 INFO - warning: no files found matching '*.txt' 05:41:03 INFO - no previously-included directories found matching 'build' 05:41:03 INFO - no previously-included directories found matching 'dist' 05:41:03 INFO - no previously-included directories found matching '.git*' 05:41:03 INFO - Installing collected packages: jsonschema, functools32 05:41:03 INFO - Running setup.py install for functools32 05:41:03 INFO - warning: no files found matching '*.txt' 05:41:03 INFO - no previously-included directories found matching 'build' 05:41:03 INFO - no previously-included directories found matching 'dist' 05:41:03 INFO - no previously-included directories found matching '.git*' 05:41:03 INFO - Successfully installed jsonschema functools32 05:41:03 INFO - Cleaning up... 05:41:04 INFO - Return code: 0 05:41:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41: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')]} 05:41:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:04 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 0x7ff22de7df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a6350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28351c0>, '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 0x28360c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2836550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', '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 05:41:04 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 05:41:04 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 05:41:04 INFO - Using env: {'DISPLAY': ':0', 05:41:04 INFO - 'HOME': '/home/cltbld', 05:41:04 INFO - 'LANG': 'en_US.UTF-8', 05:41:04 INFO - 'LANGUAGE': 'en_US:en', 05:41:04 INFO - 'LOGNAME': 'cltbld', 05:41:04 INFO - 'MAIL': '/var/mail/cltbld', 05:41:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:04 INFO - 'MOZ_NO_REMOTE': '1', 05:41:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:04 INFO - 'NO_EM_RESTART': '1', 05:41:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:04 INFO - 'PWD': '/builds/slave/test', 05:41:04 INFO - 'SHELL': '/bin/bash', 05:41:04 INFO - 'SHLVL': '1', 05:41:04 INFO - 'TERM': 'linux', 05:41:04 INFO - 'TMOUT': '86400', 05:41:04 INFO - 'USER': 'cltbld', 05:41:04 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', 05:41:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:04 INFO - '_': '/tools/buildbot/bin/python'} 05:41:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:04 INFO - Downloading/unpacking blobuploader==1.2.4 05:41:04 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 05:41:04 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 05:41:04 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 05:41:04 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 05:41:04 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 05:41:04 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 05:41:06 INFO - Downloading blobuploader-1.2.4.tar.gz 05:41:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:41:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:41:06 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 05:41:06 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 05:41:06 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 05:41:06 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 05:41:06 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 05:41:06 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 05:41:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:41:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:41:06 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 05:41:06 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 05:41:06 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 05:41:06 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 05:41:06 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 05:41:06 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 05:41:06 INFO - Downloading docopt-0.6.1.tar.gz 05:41:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:41:06 INFO - Installing collected packages: blobuploader, requests, docopt 05:41:06 INFO - Running setup.py install for blobuploader 05:41:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:41:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:41:07 INFO - Running setup.py install for requests 05:41:07 INFO - Running setup.py install for docopt 05:41:07 INFO - Successfully installed blobuploader requests docopt 05:41:07 INFO - Cleaning up... 05:41:07 INFO - Return code: 0 05:41:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41:07 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')]} 05:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:07 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')]} 05:41:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:07 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 0x7ff22de7df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a6350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28351c0>, '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 0x28360c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2836550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', '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 05:41:07 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 05:41:07 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 05:41:07 INFO - Using env: {'DISPLAY': ':0', 05:41:07 INFO - 'HOME': '/home/cltbld', 05:41:07 INFO - 'LANG': 'en_US.UTF-8', 05:41:07 INFO - 'LANGUAGE': 'en_US:en', 05:41:07 INFO - 'LOGNAME': 'cltbld', 05:41:07 INFO - 'MAIL': '/var/mail/cltbld', 05:41:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:07 INFO - 'MOZ_NO_REMOTE': '1', 05:41:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:07 INFO - 'NO_EM_RESTART': '1', 05:41:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:07 INFO - 'PWD': '/builds/slave/test', 05:41:07 INFO - 'SHELL': '/bin/bash', 05:41:07 INFO - 'SHLVL': '1', 05:41:07 INFO - 'TERM': 'linux', 05:41:07 INFO - 'TMOUT': '86400', 05:41:07 INFO - 'USER': 'cltbld', 05:41:07 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', 05:41:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:07 INFO - '_': '/tools/buildbot/bin/python'} 05:41:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:41:07 INFO - Running setup.py (path:/tmp/pip-bQG1Ak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:41:07 INFO - Running setup.py (path:/tmp/pip-GbBrBN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:41:07 INFO - Running setup.py (path:/tmp/pip-1CuS3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:41:07 INFO - Running setup.py (path:/tmp/pip-IwoRzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:41:08 INFO - Running setup.py (path:/tmp/pip-bVzGHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:41:08 INFO - Running setup.py (path:/tmp/pip-chOHkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:41:08 INFO - Running setup.py (path:/tmp/pip-LWAOJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:41:08 INFO - Running setup.py (path:/tmp/pip-BYydA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:41:08 INFO - Running setup.py (path:/tmp/pip-G96ZWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:41:08 INFO - Running setup.py (path:/tmp/pip-T8Z2M_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:41:08 INFO - Running setup.py (path:/tmp/pip-DDpDl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:41:08 INFO - Running setup.py (path:/tmp/pip-IPC5Oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:41:08 INFO - Running setup.py (path:/tmp/pip-hbTMkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:41:08 INFO - Running setup.py (path:/tmp/pip-P3vFKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:08 INFO - Running setup.py (path:/tmp/pip-GdAK8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:41:08 INFO - Running setup.py (path:/tmp/pip-qsx0ch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:41:08 INFO - Running setup.py (path:/tmp/pip-0IHASR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:41:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:41:08 INFO - Running setup.py install for manifestparser 05:41:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:41:09 INFO - Running setup.py install for mozcrash 05:41:09 INFO - Running setup.py install for mozdebug 05:41:09 INFO - Running setup.py install for mozdevice 05:41:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:41:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:41:09 INFO - Running setup.py install for mozfile 05:41:09 INFO - Running setup.py install for mozhttpd 05:41:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:41:09 INFO - Running setup.py install for mozinfo 05:41:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:41:09 INFO - Running setup.py install for mozInstall 05:41:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:41:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:41:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:41:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:41:09 INFO - Running setup.py install for mozleak 05:41:09 INFO - Running setup.py install for mozlog 05:41:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:41:10 INFO - Running setup.py install for moznetwork 05:41:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:41:10 INFO - Running setup.py install for mozprocess 05:41:10 INFO - Running setup.py install for mozprofile 05:41:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:41:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:41:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:41:10 INFO - Running setup.py install for mozrunner 05:41:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:41:10 INFO - Running setup.py install for mozscreenshot 05:41:10 INFO - Running setup.py install for moztest 05:41:10 INFO - Running setup.py install for mozversion 05:41:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:41:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:41:10 INFO - Cleaning up... 05:41:10 INFO - Return code: 0 05:41:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41: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')]} 05:41:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41: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')]} 05:41:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:10 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 0x7ff22de7df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a6350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28351c0>, '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 0x28360c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2836550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', '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 05:41:10 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 05:41:10 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 05:41:10 INFO - Using env: {'DISPLAY': ':0', 05:41:10 INFO - 'HOME': '/home/cltbld', 05:41:10 INFO - 'LANG': 'en_US.UTF-8', 05:41:10 INFO - 'LANGUAGE': 'en_US:en', 05:41:10 INFO - 'LOGNAME': 'cltbld', 05:41:10 INFO - 'MAIL': '/var/mail/cltbld', 05:41:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:10 INFO - 'MOZ_NO_REMOTE': '1', 05:41:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:10 INFO - 'NO_EM_RESTART': '1', 05:41:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:10 INFO - 'PWD': '/builds/slave/test', 05:41:10 INFO - 'SHELL': '/bin/bash', 05:41:10 INFO - 'SHLVL': '1', 05:41:10 INFO - 'TERM': 'linux', 05:41:10 INFO - 'TMOUT': '86400', 05:41:10 INFO - 'USER': 'cltbld', 05:41:10 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', 05:41:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:10 INFO - '_': '/tools/buildbot/bin/python'} 05:41:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:41:11 INFO - Running setup.py (path:/tmp/pip-WbBSgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:41:11 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:41:11 INFO - Running setup.py (path:/tmp/pip-YmY0de-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:41:11 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:41:11 INFO - Running setup.py (path:/tmp/pip-1nYhZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:41:11 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:41:11 INFO - Running setup.py (path:/tmp/pip-CaEWBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:41:11 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:41:11 INFO - Running setup.py (path:/tmp/pip-jwfi8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:41:11 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:41:11 INFO - Running setup.py (path:/tmp/pip-ep7Obb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:41:11 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:41:11 INFO - Running setup.py (path:/tmp/pip-AQLYWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:41:11 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:41:11 INFO - Running setup.py (path:/tmp/pip-0a9HOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:41:11 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:41:11 INFO - Running setup.py (path:/tmp/pip-mQLdrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:41:11 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:41:11 INFO - Running setup.py (path:/tmp/pip-SO1emU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:41:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:41:11 INFO - Running setup.py (path:/tmp/pip-HouxOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:41:11 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:41:11 INFO - Running setup.py (path:/tmp/pip-xOC1U3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:41:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:41:11 INFO - Running setup.py (path:/tmp/pip-8ZVTrO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:41:11 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)) 05:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:41:11 INFO - Running setup.py (path:/tmp/pip-NQtpdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:41:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 05:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:12 INFO - Running setup.py (path:/tmp/pip-dtT5iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:41:12 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)) 05:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:41:12 INFO - Running setup.py (path:/tmp/pip-5erSVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:41:12 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)) 05:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:41:12 INFO - Running setup.py (path:/tmp/pip-nn7oUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:41:12 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)) 05:41:12 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)) 05:41:12 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)) 05:41:12 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)) 05:41:12 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)) 05:41:12 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)) 05:41:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:41:12 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 05:41:12 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 05:41:12 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 05:41:12 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 05:41:12 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 05:41:12 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 05:41:14 INFO - Downloading blessings-1.6.tar.gz 05:41:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:41:14 INFO - Installing collected packages: blessings 05:41:14 INFO - Running setup.py install for blessings 05:41:14 INFO - Successfully installed blessings 05:41:14 INFO - Cleaning up... 05:41:14 INFO - Return code: 0 05:41:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:41:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:41:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:41:14 INFO - Reading from file tmpfile_stdout 05:41:14 INFO - Current package versions: 05:41:14 INFO - argparse == 1.2.1 05:41:14 INFO - blessings == 1.6 05:41:14 INFO - blobuploader == 1.2.4 05:41:14 INFO - docopt == 0.6.1 05:41:14 INFO - functools32 == 3.2.3-2 05:41:14 INFO - jsonschema == 2.5.1 05:41:14 INFO - manifestparser == 1.1 05:41:14 INFO - mozInstall == 1.12 05:41:14 INFO - mozcrash == 0.17 05:41:14 INFO - mozdebug == 0.1 05:41:14 INFO - mozdevice == 0.48 05:41:14 INFO - mozfile == 1.2 05:41:14 INFO - mozhttpd == 0.7 05:41:14 INFO - mozinfo == 0.9 05:41:14 INFO - mozleak == 0.1 05:41:14 INFO - mozlog == 3.2 05:41:14 INFO - moznetwork == 0.27 05:41:14 INFO - mozprocess == 0.22 05:41:14 INFO - mozprofile == 0.28 05:41:14 INFO - mozrunner == 6.11 05:41:14 INFO - mozscreenshot == 0.1 05:41:14 INFO - mozsystemmonitor == 0.3 05:41:14 INFO - moztest == 0.7 05:41:14 INFO - mozversion == 1.4 05:41:14 INFO - psutil == 3.1.1 05:41:14 INFO - requests == 1.2.3 05:41:14 INFO - wsgiref == 0.1.2 05:41:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:41:14 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')]} 05:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:14 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')]} 05:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:14 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 0x7ff22de7df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a6350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28351c0>, '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 0x28360c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2836550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', '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 05:41:14 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 05:41:14 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 05:41:14 INFO - Using env: {'DISPLAY': ':0', 05:41:14 INFO - 'HOME': '/home/cltbld', 05:41:14 INFO - 'LANG': 'en_US.UTF-8', 05:41:14 INFO - 'LANGUAGE': 'en_US:en', 05:41:14 INFO - 'LOGNAME': 'cltbld', 05:41:14 INFO - 'MAIL': '/var/mail/cltbld', 05:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:14 INFO - 'MOZ_NO_REMOTE': '1', 05:41:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:14 INFO - 'NO_EM_RESTART': '1', 05:41:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:14 INFO - 'PWD': '/builds/slave/test', 05:41:14 INFO - 'SHELL': '/bin/bash', 05:41:14 INFO - 'SHLVL': '1', 05:41:14 INFO - 'TERM': 'linux', 05:41:14 INFO - 'TMOUT': '86400', 05:41:14 INFO - 'USER': 'cltbld', 05:41:14 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', 05:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:14 INFO - '_': '/tools/buildbot/bin/python'} 05:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:14 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)) 05:41:14 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)) 05:41:14 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)) 05:41:14 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)) 05:41:14 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)) 05:41:14 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)) 05:41:14 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)) 05:41:14 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)) 05:41:14 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)) 05:41:14 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)) 05:41:14 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)) 05:41:14 INFO - Cleaning up... 05:41:14 INFO - Return code: 0 05:41:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:41:14 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')]} 05:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:14 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')]} 05:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:14 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 0x7ff22de7df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a6350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28351c0>, '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 0x28360c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2836550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', '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 05:41:14 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 05:41:14 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 05:41:14 INFO - Using env: {'DISPLAY': ':0', 05:41:14 INFO - 'HOME': '/home/cltbld', 05:41:14 INFO - 'LANG': 'en_US.UTF-8', 05:41:14 INFO - 'LANGUAGE': 'en_US:en', 05:41:14 INFO - 'LOGNAME': 'cltbld', 05:41:14 INFO - 'MAIL': '/var/mail/cltbld', 05:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:14 INFO - 'MOZ_NO_REMOTE': '1', 05:41:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:14 INFO - 'NO_EM_RESTART': '1', 05:41:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:14 INFO - 'PWD': '/builds/slave/test', 05:41:14 INFO - 'SHELL': '/bin/bash', 05:41:14 INFO - 'SHLVL': '1', 05:41:14 INFO - 'TERM': 'linux', 05:41:14 INFO - 'TMOUT': '86400', 05:41:14 INFO - 'USER': 'cltbld', 05:41:14 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', 05:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:14 INFO - '_': '/tools/buildbot/bin/python'} 05:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:41:15 INFO - Cleaning up... 05:41:15 INFO - Return code: 0 05:41:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:41:15 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')]} 05:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:15 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')]} 05:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:41:15 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff22de7df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26db8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27a6350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28351c0>, '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 0x28360c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2836550>, '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': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', '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 05:41:15 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', 'flake8'] in /builds/slave/test/build 05:41:15 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 flake8 05:41:15 INFO - Using env: {'DISPLAY': ':0', 05:41:15 INFO - 'HOME': '/home/cltbld', 05:41:15 INFO - 'LANG': 'en_US.UTF-8', 05:41:15 INFO - 'LANGUAGE': 'en_US:en', 05:41:15 INFO - 'LOGNAME': 'cltbld', 05:41:15 INFO - 'MAIL': '/var/mail/cltbld', 05:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:15 INFO - 'MOZ_NO_REMOTE': '1', 05:41:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:15 INFO - 'NO_EM_RESTART': '1', 05:41:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:15 INFO - 'PWD': '/builds/slave/test', 05:41:15 INFO - 'SHELL': '/bin/bash', 05:41:15 INFO - 'SHLVL': '1', 05:41:15 INFO - 'TERM': 'linux', 05:41:15 INFO - 'TMOUT': '86400', 05:41:15 INFO - 'USER': 'cltbld', 05:41:15 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', 05:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:15 INFO - '_': '/tools/buildbot/bin/python'} 05:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:41:15 INFO - Downloading/unpacking flake8 05:41:15 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 05:41:15 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 05:41:15 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 05:41:15 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 05:41:15 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 05:41:15 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 05:41:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:41:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:41:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 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 05:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:41:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:41:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:41:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:41:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:41:17 INFO - no previously-included directories found matching 'docs/_build' 05:41:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:41:18 INFO - Running setup.py install for pep8 05:41:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:41:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:41:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:41:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:41:18 INFO - no previously-included directories found matching 'docs/_build' 05:41:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:41:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:41:18 INFO - Cleaning up... 05:41:18 INFO - Return code: 0 05:41:18 INFO - Running post-action listener: _resource_record_post_action 05:41:18 INFO - Running post-action listener: _start_resource_monitoring 05:41:18 INFO - Starting resource monitoring. 05:41:18 INFO - ##### 05:41:18 INFO - ##### Finished create-virtualenv step (success) 05:41:18 INFO - ##### 05:41:18 INFO - ##### 05:41:18 INFO - ##### Running install step. 05:41:18 INFO - ##### 05:41:18 INFO - Running pre-action listener: _resource_record_pre_action 05:41:18 INFO - Running main action method: install 05:41:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:41:18 INFO - Reading from file tmpfile_stdout 05:41:18 INFO - Detecting whether we're running mozinstall >=1.0... 05:41:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:41:18 INFO - Reading from file tmpfile_stdout 05:41:18 INFO - Output received: 05:41:18 INFO - Usage: mozinstall [options] installer 05:41:18 INFO - Options: 05:41:18 INFO - -h, --help show this help message and exit 05:41:18 INFO - -d DEST, --destination=DEST 05:41:18 INFO - Directory to install application into. [default: 05:41:18 INFO - "/builds/slave/test"] 05:41:18 INFO - --app=APP Application being installed. [default: firefox] 05:41:18 INFO - mkdir: /builds/slave/test/build/application 05:41:18 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'] 05:41:18 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 05:41:34 INFO - Reading from file tmpfile_stdout 05:41:34 INFO - Output received: 05:41:34 INFO - /builds/slave/test/build/application/firefox/firefox 05:41:34 INFO - Running post-action listener: _resource_record_post_action 05:41:34 INFO - ##### 05:41:34 INFO - ##### Finished install step (success) 05:41:34 INFO - ##### 05:41:34 INFO - ##### 05:41:34 INFO - ##### Running run-tests step. 05:41:34 INFO - ##### 05:41:34 INFO - Running pre-action listener: _resource_record_pre_action 05:41:34 INFO - Running main action method: run_tests 05:41:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:41:34 INFO - Python 2.7.3 05:41:34 INFO - Return code: 0 05:41:34 INFO - grabbing minidump binary from tooltool 05:41:34 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')]} 05:41:34 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 0x28351c0>, '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 0x28360c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2836550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:41:34 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 05:41:34 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 05:41:34 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 05:41:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:41:34 INFO - Return code: 0 05:41:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:41:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:41:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:41:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:41:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:41:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:41:35 INFO - Return code: 0 05:41:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 05:41:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-068 --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 05:41:35 INFO - Using env: {'DISPLAY': ':0', 05:41:35 INFO - 'HOME': '/home/cltbld', 05:41:35 INFO - 'LANG': 'en_US.UTF-8', 05:41:35 INFO - 'LANGUAGE': 'en_US:en', 05:41:35 INFO - 'LOGNAME': 'cltbld', 05:41:35 INFO - 'MAIL': '/var/mail/cltbld', 05:41:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:41:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:41:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:41:35 INFO - 'MOZ_NO_REMOTE': '1', 05:41:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:41:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:41:35 INFO - 'NO_EM_RESTART': '1', 05:41:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:41:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:41:35 INFO - 'PWD': '/builds/slave/test', 05:41:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:41:35 INFO - 'SHELL': '/bin/bash', 05:41:35 INFO - 'SHLVL': '1', 05:41:35 INFO - 'TERM': 'linux', 05:41:35 INFO - 'TMOUT': '86400', 05:41:35 INFO - 'USER': 'cltbld', 05:41:35 INFO - 'XDG_SESSION_COOKIE': '97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255', 05:41:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:41:35 INFO - '_': '/tools/buildbot/bin/python'} 05:41:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-068', '--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 05:41:35 INFO - mozversion application_buildid: 20160616051126 05:41:35 INFO - mozversion application_changeset: 61b7d2351dd2ed50789df8aed472ee0af1e06829 05:41:35 INFO - mozversion application_display_name: Nightly 05:41:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:41:35 INFO - mozversion application_name: Firefox 05:41:35 INFO - mozversion application_remotingname: firefox 05:41:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:41:35 INFO - mozversion application_vendor: Mozilla 05:41:35 INFO - mozversion application_version: 50.0a1 05:41:35 INFO - mozversion platform_buildid: 20160616051126 05:41:35 INFO - mozversion platform_changeset: 61b7d2351dd2ed50789df8aed472ee0af1e06829 05:41:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:41:35 INFO - mozversion platform_version: 50.0a1 05:41:35 INFO - using testdate: 1466080895 05:41:35 INFO - actual date: 1466080895 05:41:35 INFO - starting webserver on 'localhost:36989' 05:41:35 INFO - SUITE-START | Running 2 tests 05:41:35 INFO - TEST-START | dromaeo_css 05:41:35 INFO - operating with platform_type : linux_ 05:41:35 INFO - Initialising browser for dromaeo_css test... 05:41:35 INFO - TEST-INFO | started process 29064 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDd9mwC/profile http://localhost:36989/getInfo.html) 05:41:41 INFO - PROCESS | 29064 | __metrics Screen width/height:1600/1200 05:41:41 INFO - PROCESS | 29064 | colorDepth:24 05:41:41 INFO - PROCESS | 29064 | Browser inner width/height: 1024/697 05:41:41 INFO - PROCESS | 29064 | __metrics 05:41:41 ERROR - PROCESS | 29064 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 05:41:42 INFO - TEST-INFO | 29064: exit 0 05:41:42 INFO - Browser initialized. 05:41:42 INFO - Running cycle 1/1 for dromaeo_css test... 05:41:42 INFO - TEST-INFO | started process 29184 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDd9mwC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:54 INFO - PROCESS | 29184 | RSS: Main: 186527744 05:41:54 INFO - PROCESS | 29184 | 05:42:44 INFO - PROCESS | 29184 | 1466080964514 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] 05:44:57 INFO - PROCESS | 29184 | Cycle 1(1): loaded http://localhost:36989/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36989/tests/dromaeo/cssquery-ext.html) 05:44:58 INFO - PROCESS | 29184 | RSS: Main: 189304832 05:44:58 INFO - PROCESS | 29184 | 05:48:00 INFO - PROCESS | 29184 | Cycle 1(1): loaded http://localhost:36989/tests/dromaeo/cssquery-ext.html (next: http://localhost:36989/tests/dromaeo/cssquery-jquery.html) 05:48:01 INFO - PROCESS | 29184 | RSS: Main: 188325888 05:48:01 INFO - PROCESS | 29184 | 05:51:03 INFO - PROCESS | 29184 | Cycle 1(1): loaded http://localhost:36989/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36989/tests/dromaeo/cssquery-mootools.html) 05:51:03 INFO - PROCESS | 29184 | RSS: Main: 189222912 05:51:03 INFO - PROCESS | 29184 | 05:54:05 INFO - PROCESS | 29184 | Cycle 1(1): loaded http://localhost:36989/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36989/tests/dromaeo/cssquery-prototype.html) 05:54:06 INFO - PROCESS | 29184 | RSS: Main: 186736640 05:54:06 INFO - PROCESS | 29184 | 05:57:08 INFO - PROCESS | 29184 | Cycle 1(1): loaded http://localhost:36989/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36989/tests/dromaeo/cssquery-yui.html) 05:57:09 INFO - PROCESS | 29184 | RSS: Main: 188899328 05:57:09 INFO - PROCESS | 29184 | 06:00:12 INFO - PROCESS | 29184 | Cycle 1(1): loaded http://localhost:36989/tests/dromaeo/cssquery-yui.html (next: http://localhost:36989/tests/dromaeo/cssquery-dojo.html) 06:00:12 INFO - PROCESS | 29184 | RSS: Main: 189718528 06:00:12 INFO - PROCESS | 29184 | 06:00:12 INFO - PROCESS | 29184 | __start_tp_report 06:00:12 INFO - PROCESS | 29184 | _x_x_mozilla_page_load 06:00:12 INFO - PROCESS | 29184 | _x_x_mozilla_page_load_details 06:00:12 INFO - PROCESS | 29184 | |i|pagename|runs| 06:00:12 INFO - PROCESS | 29184 | |0;dojo.html;2647.139679604129;2701.9054333098347;2704.2698471412705;2707.4855777402304;2718.605802158686;4791.448983366914;4814.783334749936;4817.421909370876;4888.926666100011;4927.384076990376;4748.762561871901;4829.492903245153;4877.561019508244;4915.967646794169;4939.506049395068;4421.248387774078;4440.711353761995;4522.253828118356;4553.5901768840795;4559.17934771741;4237.216115018716;4521.728696278217;4522.8643140705835;4606.493285738566;4609.354690343354;4436.3123715824095;4445.377647129404;4453.398791163217;4486.887827804331;4552.11233809404;4405.096955124224;4411.316245981878;4476.462824461053;4497.302918047708;4518.683692141552;4453.376527286183;4488.932666010012;4499.03270796777;4505.504394516601;4530.977345113286;4801.807927682923;4850.248211527219;4881.926771098437;4896.192128298826;4911.656184067117;5203.531682148625;5210.505002024802;5216.113260745682;5242.056429842627;5261.053010458116;5145.434002259786;5250.816221432232;5274;5283.128283833163;5285.762140703678;4876.439209490883;4928.433230178575;4932.334134891744;4953.479884612064;4986.202207646759;5398.973005135028;5413.133898576288;5421.566274698014;5422.53908417781;5464.699441530674;5065.088284108896;5162.174052151638;5192.4547922468355;5197.090509160882;5200.7919683213;5180.248863914711;5188.351456067991;5312.893742125136;5321.547668448148;5374.838754837283;5151.22731590257;5187.455317191716;5190.740462976836;5208.479152084761;5217.452167522356;5063.291139240436;5127.66670166438;5148.433672296044;5192.299039629677;5258.7370631468275;4365.279728844768;4388.71473354231;4390.253656878338;4415.359772832921;4427.203103441347;3810.8666196682984;3831.8084095795098;3833.290841194388;3861.7876016819346;3881.2819628368843;4473.731576105444;4480.238359478896;4515.322701594721;4564.475085365226;4609.193391156534;5240.266362709223;5280;5295.947040529545;5300.840974770763;5352.090144675414;4535.206338890681;4549.135664223787;4554.88612784683;4559.6808223424705;4599.563041511051;4568.291914753218;4638.141943740419;4653.7207767534055;4739.905201896012;4781.091592597395;4896.4124305083615;4909.361782968192;4929.383827021622;4932.161532539405;4967.205247160509;5212;5229.372475302988;5229.660072095302;5237.319148510669;5242;4825.95174048255;4915.3855768029;4918.458969513422;4940.357753492167;4992.550670439678;5223.373195216598;5233.973830130825;5237.947620523746;5238.371395432572;5259.658122222043;5252.395974462986;5268.209768534751;5269.077911365573;5271.051210782096;5327.413984461784;4963.553280204799;4983.103041452573;5025.195968644999;5049.57078648319;5081;5027.296178534935;5048.167052436307;5071.568916642127;5072.67027643202;5130.97434512825;4961.578265847299;4982.153033984159;4992.400911890596;5014.147594908802;5023.447420783637;4967.503249675148;5100.2349647553165;5128.205128205134;5148.742562871917;5167.509086636764;5012.172991456368;5046.369203849519;5061.721605311596;5078.08594453002;5107.821226257063 06:00:12 INFO - PROCESS | 29184 | |1;ext.html;562.9521490673293;578.3984655957803;586.129597547642;598.7964092208651;606.3421188011008;35261.47107793385;38974;39014.21971560567;39034;39036.41445378322;38478.61521384785;39257.21485570287;39272;39376.409353859715;39380;7742.8451430971345;7956.562389068599;7960.203979602052;7960.6019699015105;7962.960185199066;7848.68605255789;7893.289603935645;7913.248241417056;7913.723019694312;7933;7855.332296754754;7866.370690344787;7868.2918537331625;7873.291403773688;7919.604019799016;7837.2554607312495;7860.056793184797;7878.09401918781;7891.881621775678;7905.96047019767;7796.766097017042;7928;7934.127246002935;7943.086545047336;7960.1243863175005;36628.450573241425;36916.81541592275;37302;37380.25239495222;37538.43692344617;2958.156925276286;3069.2326918270433;3346.665333466658;3602.423612222058;3755.286495565834;29864.850675746482;30013.099607011824;30134;30148.397032059456;30376.392472150656;3547.8935631931085;3622.4385220290897;3625.764325318872;3689.981550092259;3761.6614504694708;241550;242482;242925;243205;244174;31399.21501962469;32270.19324516906;32352.83823580867;34232.65767342345;51836.48163518317;33792;33836.32327353489;33912.49131263033;34054.65945340515;34144;11679.065674746;11730.472128754227;11771.764564708828;11775.882241177478;11818.054555635528;9587.664431744855;9607.375520591138;9612.471314077653;9700.805983880424;9783.559739811726;7873.763787086396;7879.251471110236;7974.920250797418;8016.358691304682;8041.6381262843315;3113.517404802259;3132.3265497918055;3143.717065464119;3202.5676533668125;3208.8876889308763;856.4261944497201;869.9391042627082;919;966.3042609321277;1005.7435353984687;28261.57607635887;28321.858390708327;28744;28766.71233287682;29156.562651560245;872.5026734761125;948.2414068744977;962.8170647576937;1036.4765793274348;1084.0297933349607;14562.781558276634;14591.708165836624;14664.266786660624;14910.552683419515;15046.172460514777;407760;408348;408984;409219;410346;49811.50565483041;50881;51014.48985510172;51035.23447148296;51111.23333150006;1132.184826924613;1133.9092872570175;1147.1453766943514;1171.00464605085;1197.383347575999;26416.73583264143;26564.468710625293;27094.593581095905;27270.72729272682;27375.726242737317;42015.7899210502;42059.579404206794;42205;42236.155276894904;42333.788331058146;337263;340552;343429;344218;344504;25028.499430011663;25161;25326.746732533175;25733;25883.482330352912;40847.38728919128;41094.1781164381;41971.16057678768;42084.36873446962;42090.57909420989;36101.27797444089;36163.457548137325;36294;36557.817210913774;36745;35339.64660353467;35505.1123721909;35704.64295357117;35889.82055089813;35978.64021359753;34182.487262690585;35233.29533409369;35284;35932.820335898155;36314.27371452609;31169;32988.88672255804;33125.503117453736;33219;33316;25322.90015847846;25611.871940640176;25684.614730779427;25692.614610780478;25902.222933311277 06:00:12 INFO - PROCESS | 29184 | |2;jquery.html;360.4575114452749;368.52092280035953;370.22622718518306;376.55002272284594;388.2119297825415;15389.538313850588;16787.74818377724;17611.73582396262;17643.735343969835;17668.38160664377;16047.117408542526;16419;17144.914275428604;17170.82829171708;17190.484285471426;5131.717755523454;5230.7646155922885;5277.70972596507;5324.281222035014;5329.520343169114;3759.774413535197;5152.046871328797;5874.588778785486;6257.155284036642;6259.499240060807;6181.165542651729;6216.658083805389;6281.152044473984;6299.622022678631;6326.240851097875;6153.7846175383875;6299.559030867796;6304.05439184126;6307;6325.1777268954975;5534.252875861747;5559.388467268597;5567.693776842272;5583.944160558383;5608.9439105609235;20471.38585842427;20699;21479.785202147934;21485.67771483429;21490.785092149035;1897.089397089391;1911.2259534888392;1928.1227041695995;1939.8545109116872;1950.2296592845746;13287.335633218301;13406;13431.261280629562;13634.590962271148;14055.578332650037;1916.281394477071;1965.4202010406964;1978.3768113044346;1984.8908310042941;2005.809448102428;72736.6363168186;75890;76431.23568764297;76620.23379766187;77422.61288693521;15917.761233581505;16125.95181313211;16211.432599859185;16481.25834337458;16583.17084145788;27691.86154069257;27956.161315160578;27991.300217494725;28012.85993570019;28087.297817554725;8033.879491807627;8080.959595201986;8206.261436470746;8450.619722112397;8481.109483504266;6509.804705858832;6566.080748695186;6589.308122647149;6820.829479262959;6830.692618832164;8374.728888267027;8378.58107094655;8441;8518.403711740242;8675.479471231747;1548.8141423029308;1564.7261729197344;1584.8890577659454;1620.4085508789442;1623.6103335199466;859.4542465534431;894.6466145872344;899.9820003600022;924.9039887733057;930.3069213435898;16113.677726445405;16359.83640163607;16547.420840270534;16689.415870444474;16794.580135496464;832.8417600655856;843.4559708987669;859.7576501954741;867.2758246863939;870.3950754225752;9399.90600094004;9417.90582094184;9463.337566370288;9645.469499177472;9788.853167202498;378646;380673;383180;384737;384955;77071;77567.61216193883;77992.61003694945;79591.60204198942;79946.6002669983;931.0875342164582;941.0730430525927;947.7535840681472;951.0204489376092;953.413650604873;15155.469558565397;15270.541883743506;15420.537383878502;15481.148536830582;15520;38421.4236786454;38450.230995379374;38917;40522.1895562093;40845.1830963385;53242.73378633082;53826;54813;54943.45056549383;56187;7582.469227154046;7680.846383072419;7704.576248306397;7749.651265692944;7872.488288261245;8116.107228204774;8177.67289308421;8343.332533397435;8387.287080598218;8402.41183117176;7366.373858222111;7424.554526728413;7574.431917606092;7597.696092156473;7718.845623087619;7299.233580473974;7418.36943859778;7622.961885190539;7638.579878106758;7884.645190966306;7565.6973721050545;7617.238276172338;7685;7809;7948.682052717826;10662.573497060032;10707.732306692084;10850.728731781768;10922.672319830417;11036.944815275872;10545.894541054799;10847.891521084688;11175.105991520497;11215;11350.716232094264 06:00:12 INFO - PROCESS | 29184 | |3;mootools.html;1387.0360099730688;1415.8159439272893;1462.9707405851884;1662.4513910409567;1672.3059930128998;6980.895286570705;7620.923790762091;7737.922620773805;8179.141190175028;8210;7693.615319234043;7933.722319718811;8005.359571234302;8033.357331413487;8038.155993620659;2189.211883721859;2241.4172315198084;2241.9887900560475;2243.6410174372104;2248.7301523817164;2183.7161169048;2193.7915897989747;2203.019656252968;2207.536417352358;2215.6898034275214;2139.1657253671083;2155.9353219403365;2161.492049368397;2167.306461932182;2195.407240045187;2100.69539916712;2111.4721319670084;2112.8309735221146;2113.5244569971633;2120.1943261560587;2106.8946552672305;2128.957420851574;2131.05168200151;2144.1852096203497;2152.2467136502255;7975.282224599814;8067.233612806774;8076.757697269091;8107.797305067362;8138.308243799284;4357.891052723676;4437.312216606431;4457;4461.553844615512;4465.977670111661;2836.120802551195;2920.6057182280333;2956.9852150739107;2966.362232120083;2969.6584892737396;858.6822875536029;875.614729519016;880.0143838900409;889.4929889962724;905.3210092430677;11131.109511239081;11162.497687604076;11167.329960202414;11170.497327620276;11184.44077796107;4136.710430269853;4192.538820729717;4225.2817021105875;4252.766097864647;4276.743395396286;9314.90685093154;9365.344425890122;9378.624855005859;9380.343375963617;9394.718158455258;4561.977190114028;4581.5647513486165;4583.679142459996;4587.334836448763;4593.494715581351;3576.481410195559;3981.024648961023;4100.569440208795;4155.501339839178;4183.790810459464;3618.8914332570066;3634.7637403568683;3711.350513660099;3742.419924911643;3780.017195529127;1194.9701257468632;1205.7528206717602;1225.197495640357;1227.1287385955893;1227.9570215042431;772.9574873381905;784.2157842157842;811.5009269299456;816.4488969945263;835.3609488741118;4967.3542439482635;5062.316587260672;5243;5259.368875734936;5314.813981510629;773.7446642607925;816.4774544291658;856.1524091149842;861.1087524412203;864.6757465950269;3413.709834664032;3489.7731647443343;3543.167355671415;3630.0380399194237;3663.9084022899106;9717.56270967808;9995.550200241007;10008.09927106564;10073.748156296151;10076.596936122472;10031.297809153435;10032.749181270527;10042.14641755459;10051.04515071067;10076.798464030679;7284.053073100463;7360.411167106726;7403.962980185064;7452.627368631655;7498.775036748906;4953.23224900141;4971.279164521196;4995.550400463976;5012.8496145115705;5030.547250747451;8882.600282987152;8902.910970890467;8932.821343573221;9127.908720912706;9160.404573702688;9002.729918102468;9083;9230;9230.953845230732;9298.442093474414;5943;6242;6249.375062493714;6289.96855015722;6301.684915754286;7568.8486230276185;7617.200193979553;7658.119316278699;7682.308592226726;7701.306882380389;6498.967505162444;6831.077804496329;6882.827929301808;6905.309469053255;7110.39561637246;6769.627670478171;6794.898076528757;6800.727970881308;6892.414144797748;6908.896366554405;6240.968795155995;6289.308176100534;6300.779472718433;6321.905171422341;6349.07938348946;4381.912361752811;4438.733675979452;4506.932396013997;4520.344550040291;4551.544845515422;4517.073999829961;4534.818607255805;4583.679142459996;4593.977030114828;4607.447106347126 06:00:12 INFO - PROCESS | 29184 | |4;prototype.html;293.3677924073621;296.93912747886685;298.7490507973302;299.8965356951851;302.1253471200875;5106.795728170873;5133;5166.328377310953;5172.0948833954;5181.196914478252;5058.5953123750105;5096.898062038757;5127.512886275807;5134.25553294773;5160.174372100455;3704.5554533456025;3862;3955.9011024724323;3960.841566337343;3986.9601303987024;3882.9223415531674;3924.3721004639265;3952.565217826037;3967.444557761916;4010.11777408971;3970.166265084336;3981.1042515434087;3984.3027470192746;3993.7004724645767;4002.679115891763;3781;3932.1939002504705;3953.920921581581;3970.9873982134513;3989.501312335958;3418.85468368097;3446.7070299024613;3454.4300190468543;3454.481827725836;3457.9827100864336;5000.499950005007;5021.673591216596;5026.371703537058;5030.874228144289;5041.571466425358;4386.151810798896;4524.389207456984;4553.567411095941;4553.635709143261;4554.223852697451;5293.602979776532;5315.3621565412395;5325.7869685212545;5337.599680024014;5383;3846.038520354611;3869.7616762635967;3874.5544262409903;3875.5466699987505;3896.902577435558;5473.987312347229;5568.969740598002;5573.804916827891;5644.74598643062;5668;3816.358965644744;3828.693704503633;3832.1952389998414;3837.424386342015;3844.2888065707934;3879.1271963807917;3902;3902.648761611412;3907.355286377716;3934.8229329680235;4521;4522.638188944877;4533.138703646297;4537.795799189044;4549.294859296814;5152.845414637567;5337.759800808973;5352.277442545284;5391.272178255964;5402.729863506809;1372.9519466818613;1390.0686540018212;1391.4503771010395;1402.2778269191374;1404.7778432763562;839.7228914458215;853.7182729699184;875.59722527638;883.9911600884037;894.8478758611052;4517.34498497716;4702.059588082398;4707.835225767081;4709.270063140219;4743.66794324394;5555.805546805843;5594.049011668027;5598.888022239614;5602.747876345574;5619.550435965113;4576.748278844629;4643.2802915548145;4667.743274119888;4707.129181101507;4736.360591320196;2904.8257104573795;2941.6617089035035;2949.7640188784844;2959.978807311474;2961.0228624553847;5170.870728231825;5189.974050129726;5218.817341393109;5231.659942103751;5241.397239317527;5297.649099479608;5645.774169033275;5671.688057156814;5712.885742285131;5738.598298119092;5230.869228269324;5248;5264.131418316011;5283.313169288045;5294.232336311136;5548.363232846401;5554.389017208185;5563.471470210324;5565.666060036411;5568.554515638658;5233.476652334736;5430.375506816767;5432.402435731988;5449.972750136224;5454.072807622634;5182.559482444034;5218.799676074448;5246.711430871339;5273.815416460559;5338.599605029561;3807.7715337079862;3826.5790763016603;3834.8082595869537;3834.8082595869537;3837.884863454101;5365.946340536545;5470.8905821882545;5529.281193444987;5553.389127196086;5554.638948468337;5289.814856480005;5349.705766182898;5368.2752828367675;5374.468276541167;5391.62258641907;5249.580033597226;5359.249705041375;5370.275012873212;5398.865028374322;5408.998670491943;5195.272661827423;5214.452482489284;5278.287431196739;5294.576433885357;5297.390800057889;5139.07284768212;5277.788888444419;5452.318460192476;5455.263539422286;5455.5908306876345;5224.9738751306;5305.575553955597;5343.946560534345;5359.4104648487855;5366.758495867617 06:00:12 INFO - PROCESS | 29184 | |5;yui.html;656.2977613953069;693.8161387232383;698.0422097398703;705.3686950179589;760.3954855889567;12984.415701293441;13747;13808.378622961978;14012.64968375791;14275.286235688225;14791;14912.478063267788;14974.176420296879;14994.550163495085;15011.924940375282;628.2304177382692;754.9584772837492;780.4302858912997;794.3922898982283;795.8806179073157;735.0114096540145;771.1093686791736;785.3285440947993;785.4227143049853;785.6111224943661;835.6448509383519;842.8356470488227;844.5945945945949;845.4589062999685;847.3729440214228;830.1739768929954;830.5182993863605;830.6926437218237;831.2560258568548;836.0051538668964;610.1305639463747;610.2707264818544;611.4649681528663;611.8500967263004;612.7548980407828;14147.858521414757;14295.142291462442;14304.713905721932;14367.78448323276;14376.712465750627;13385.19688818674;13572.389242484112;13611.319434028259;13637.386317615732;13688.110272832235;10430.113440357578;10493.213009024277;10506.684799456028;10688.305260158142;10750.086242669382;3280.2947366316116;3291.8683252669866;3328.8834890778708;3334.7999120052873;3340.816255105968;68141.65929170372;68266.65866670635;68288.31711682919;68814.3118568813;69318.65340673315;9726.562304696306;10155.593776249016;10238.232132590085;10534.157267418588;10589.682309530726;19248.23007079729;19371.806281937283;19379.546534009725;19391;19598.608027839364;6794.932050679528;6812.795616131524;6824.4199243064895;6907.102076729993;6930.203026652;3654.908627284286;3665.6884164846288;3714.7028237740915;3774.433834924728;3787.5644300905747;2915.3149009982635;3016.4117996990817;3029.348690031654;3032.0297504798255;3033.5601337805892;470.5318208382681;484.9030193961222;492.9186684197139;498.01890276155916;500.6620531141465;418.7089972469105;462.21192866163113;462.26269100784197;500.96743711658627;516.657227736544;7420.109586849612;7444.952638024586;7536.171021187665;7585.848283034308;7665.693372265158;453.26570955052364;453.32454642582974;456.1766012347678;466.6709969471514;475.8239451403003;3609.4405367168133;3667.5232219811787;3670.559532856021;3697.279030589063;3725;102043;102499;105491;106160.46919765351;106667;52310.73844630752;78615.2138478608;86562.13437865667;87887;88711.11288887028;50158.749206253735;50348.24477632912;68057;74044.88932666014;81446.59276703579;6908.93091069103;6924.58452492852;6991.3008699131715;7048.365647091787;7120.465965052538;33423.33153336968;38659.6134038656;46255.53744462512;47263;48199;53054.4694553065;53811.461885380646;55162.72418637881;62856.685716571126;63750.3624963763;10303.433311167957;10591.523381448007;10876.347419154876;11034.282771619819;11318.717032074264;13060.542880999119;13069.934650327068;13086.411111499814;13146.21122732639;13153.802692959422;11028.724281893017;11076.667699968692;11088.667339979813;11221.719457013314;11454.370009649216;11551.422428878355;11884.821727674265;12415.503379864704;12512.812307815208;13044.282564459047;11585.478653460783;11726.70683232926;11771.117166212398;11880.821787673365;12129.818052729393;9555.522223888916;9718.659846905324;9768.85346719814;9950.850737239092;10275.434851082962;10051.346662466916;10504.26470147113;10513.526891289757;10573.52419141156;10700.41147736882 06:00:12 INFO - PROCESS | 29184 | __end_tp_report 06:00:12 INFO - PROCESS | 29184 | __start_cc_report 06:00:12 INFO - PROCESS | 29184 | _x_x_mozilla_cycle_collect,401 06:00:12 INFO - PROCESS | 29184 | __end_cc_report 06:00:12 INFO - PROCESS | 29184 | __startTimestamp1466082012248__endTimestamp 06:00:12 INFO - PROCESS | 29184 | 06:00:12 INFO - PROCESS | 29184 | ------- Summary: start ------- 06:00:12 INFO - PROCESS | 29184 | Number of tests: 6 06:00:12 INFO - PROCESS | 29184 | 06:00:12 INFO - PROCESS | 29184 | [#0] dojo.html Cycles:165 Average:4832.37 Median:4961.58 stddev:518.81 (10.5%) stddev-sans-first:491.26 06:00:12 INFO - PROCESS | 29184 | Values: 2647.1 2701.9 2704.3 2707.5 2718.6 4791.4 4814.8 4817.4 4888.9 4927.4 4748.8 4829.5 4877.6 4916.0 4939.5 4421.2 4440.7 4522.3 4553.6 4559.2 4237.2 4521.7 4522.9 4606.5 4609.4 4436.3 4445.4 4453.4 4486.9 4552.1 4405.1 4411.3 4476.5 4497.3 4518.7 4453.4 4488.9 4499.0 4505.5 4531.0 4801.8 4850.2 4881.9 4896.2 4911.7 5203.5 5210.5 5216.1 5242.1 5261.1 5145.4 5250.8 5274.0 5283.1 5285.8 4876.4 4928.4 4932.3 4953.5 4986.2 5399.0 5413.1 5421.6 5422.5 5464.7 5065.1 5162.2 5192.5 5197.1 5200.8 5180.2 5188.4 5312.9 5321.5 5374.8 5151.2 5187.5 5190.7 5208.5 5217.5 5063.3 5127.7 5148.4 5192.3 5258.7 4365.3 4388.7 4390.3 4415.4 4427.2 3810.9 3831.8 3833.3 3861.8 3881.3 4473.7 4480.2 4515.3 4564.5 4609.2 5240.3 5280.0 5295.9 5300.8 5352.1 4535.2 4549.1 4554.9 4559.7 4599.6 4568.3 4638.1 4653.7 4739.9 4781.1 4896.4 4909.4 4929.4 4932.2 4967.2 5212.0 5229.4 5229.7 5237.3 5242.0 4826.0 4915.4 4918.5 4940.4 4992.6 5223.4 5234.0 5237.9 5238.4 5259.7 5252.4 5268.2 5269.1 5271.1 5327.4 4963.6 4983.1 5025.2 5049.6 5081.0 5027.3 5048.2 5071.6 5072.7 5131.0 4961.6 4982.2 4992.4 5014.1 5023.4 4967.5 5100.2 5128.2 5148.7 5167.5 5012.2 5046.4 5061.7 5078.1 5107.8 06:00:12 INFO - PROCESS | 29184 | 06:00:12 INFO - PROCESS | 29184 | [#1] ext.html Cycles:180 Average:46879.89 Median:19959.88 stddev:89475.63 (448.3%) stddev-sans-first:89659.05 06:00:12 INFO - PROCESS | 29184 | Values: 563.0 578.4 586.1 598.8 606.3 35261.5 38974.0 39014.2 39034.0 39036.4 38478.6 39257.2 39272.0 39376.4 39380.0 7742.8 7956.6 7960.2 7960.6 7963.0 7848.7 7893.3 7913.2 7913.7 7933.0 7855.3 7866.4 7868.3 7873.3 7919.6 7837.3 7860.1 7878.1 7891.9 7906.0 7796.8 7928.0 7934.1 7943.1 7960.1 36628.5 36916.8 37302.0 37380.3 37538.4 2958.2 3069.2 3346.7 3602.4 3755.3 29864.9 30013.1 30134.0 30148.4 30376.4 3547.9 3622.4 3625.8 3690.0 3761.7 241550.0 242482.0 242925.0 243205.0 244174.0 31399.2 32270.2 32352.8 34232.7 51836.5 33792.0 33836.3 33912.5 34054.7 34144.0 11679.1 11730.5 11771.8 11775.9 11818.1 9587.7 9607.4 9612.5 9700.8 9783.6 7873.8 7879.3 7974.9 8016.4 8041.6 3113.5 3132.3 3143.7 3202.6 3208.9 856.4 869.9 919.0 966.3 1005.7 28261.6 28321.9 28744.0 28766.7 29156.6 872.5 948.2 962.8 1036.5 1084.0 14562.8 14591.7 14664.3 14910.6 15046.2 407760.0 408348.0 408984.0 409219.0 410346.0 49811.5 50881.0 51014.5 51035.2 51111.2 1132.2 1133.9 1147.1 1171.0 1197.4 26416.7 26564.5 27094.6 27270.7 27375.7 42015.8 42059.6 42205.0 42236.2 42333.8 337263.0 340552.0 343429.0 344218.0 344504.0 25028.5 25161.0 25326.7 25733.0 25883.5 40847.4 41094.2 41971.2 42084.4 42090.6 36101.3 36163.5 36294.0 36557.8 36745.0 35339.6 35505.1 35704.6 35889.8 35978.6 34182.5 35233.3 35284.0 35932.8 36314.3 31169.0 32988.9 33125.5 33219.0 33316.0 25322.9 25611.9 25684.6 25692.6 25902.2 06:00:12 INFO - PROCESS | 29184 | 06:00:12 INFO - PROCESS | 29184 | [#2] jquery.html Cycles:180 Average:25175.94 Median:30238.85 stddev:63343.72 (209.5%) stddev-sans-first:63494.02 06:00:12 INFO - PROCESS | 29184 | Values: 360.5 368.5 370.2 376.6 388.2 15389.5 16787.7 17611.7 17643.7 17668.4 16047.1 16419.0 17144.9 17170.8 17190.5 5131.7 5230.8 5277.7 5324.3 5329.5 3759.8 5152.0 5874.6 6257.2 6259.5 6181.2 6216.7 6281.2 6299.6 6326.2 6153.8 6299.6 6304.1 6307.0 6325.2 5534.3 5559.4 5567.7 5583.9 5608.9 20471.4 20699.0 21479.8 21485.7 21490.8 1897.1 1911.2 1928.1 1939.9 1950.2 13287.3 13406.0 13431.3 13634.6 14055.6 1916.3 1965.4 1978.4 1984.9 2005.8 72736.6 75890.0 76431.2 76620.2 77422.6 15917.8 16126.0 16211.4 16481.3 16583.2 27691.9 27956.2 27991.3 28012.9 28087.3 8033.9 8081.0 8206.3 8450.6 8481.1 6509.8 6566.1 6589.3 6820.8 6830.7 8374.7 8378.6 8441.0 8518.4 8675.5 1548.8 1564.7 1584.9 1620.4 1623.6 859.5 894.6 900.0 924.9 930.3 16113.7 16359.8 16547.4 16689.4 16794.6 832.8 843.5 859.8 867.3 870.4 9399.9 9417.9 9463.3 9645.5 9788.9 378646.0 380673.0 383180.0 384737.0 384955.0 77071.0 77567.6 77992.6 79591.6 79946.6 931.1 941.1 947.8 951.0 953.4 15155.5 15270.5 15420.5 15481.1 15520.0 38421.4 38450.2 38917.0 40522.2 40845.2 53242.7 53826.0 54813.0 54943.5 56187.0 7582.5 7680.8 7704.6 7749.7 7872.5 8116.1 8177.7 8343.3 8387.3 8402.4 7366.4 7424.6 7574.4 7597.7 7718.8 7299.2 7418.4 7623.0 7638.6 7884.6 7565.7 7617.2 7685.0 7809.0 7948.7 10662.6 10707.7 10850.7 10922.7 11036.9 10545.9 10847.9 11175.1 11215.0 11350.7 06:00:12 INFO - PROCESS | 29184 | 06:00:12 INFO - PROCESS | 29184 | [#3] mootools.html Cycles:180 Average:5178.58 Median:4593.74 stddev:2977.49 (64.8%) stddev-sans-first:2972.21 06:00:12 INFO - PROCESS | 29184 | Values: 1387.0 1415.8 1463.0 1662.5 1672.3 6980.9 7620.9 7737.9 8179.1 8210.0 7693.6 7933.7 8005.4 8033.4 8038.2 2189.2 2241.4 2242.0 2243.6 2248.7 2183.7 2193.8 2203.0 2207.5 2215.7 2139.2 2155.9 2161.5 2167.3 2195.4 2100.7 2111.5 2112.8 2113.5 2120.2 2106.9 2129.0 2131.1 2144.2 2152.2 7975.3 8067.2 8076.8 8107.8 8138.3 4357.9 4437.3 4457.0 4461.6 4466.0 2836.1 2920.6 2957.0 2966.4 2969.7 858.7 875.6 880.0 889.5 905.3 11131.1 11162.5 11167.3 11170.5 11184.4 4136.7 4192.5 4225.3 4252.8 4276.7 9314.9 9365.3 9378.6 9380.3 9394.7 4562.0 4581.6 4583.7 4587.3 4593.5 3576.5 3981.0 4100.6 4155.5 4183.8 3618.9 3634.8 3711.4 3742.4 3780.0 1195.0 1205.8 1225.2 1227.1 1228.0 773.0 784.2 811.5 816.4 835.4 4967.4 5062.3 5243.0 5259.4 5314.8 773.7 816.5 856.2 861.1 864.7 3413.7 3489.8 3543.2 3630.0 3663.9 9717.6 9995.6 10008.1 10073.7 10076.6 10031.3 10032.7 10042.1 10051.0 10076.8 7284.1 7360.4 7404.0 7452.6 7498.8 4953.2 4971.3 4995.6 5012.8 5030.5 8882.6 8902.9 8932.8 9127.9 9160.4 9002.7 9083.0 9230.0 9231.0 9298.4 5943.0 6242.0 6249.4 6290.0 6301.7 7568.8 7617.2 7658.1 7682.3 7701.3 6499.0 6831.1 6882.8 6905.3 7110.4 6769.6 6794.9 6800.7 6892.4 6908.9 6241.0 6289.3 6300.8 6321.9 6349.1 4381.9 4438.7 4506.9 4520.3 4551.5 4517.1 4534.8 4583.7 4594.0 4607.4 06:00:12 INFO - PROCESS | 29184 | 06:00:12 INFO - PROCESS | 29184 | [#4] prototype.html Cycles:180 Average:4447.23 Median:5136.66 stddev:1306.46 (25.4%) stddev-sans-first:1272.38 06:00:12 INFO - PROCESS | 29184 | Values: 293.4 296.9 298.7 299.9 302.1 5106.8 5133.0 5166.3 5172.1 5181.2 5058.6 5096.9 5127.5 5134.3 5160.2 3704.6 3862.0 3955.9 3960.8 3987.0 3882.9 3924.4 3952.6 3967.4 4010.1 3970.2 3981.1 3984.3 3993.7 4002.7 3781.0 3932.2 3953.9 3971.0 3989.5 3418.9 3446.7 3454.4 3454.5 3458.0 5000.5 5021.7 5026.4 5030.9 5041.6 4386.2 4524.4 4553.6 4553.6 4554.2 5293.6 5315.4 5325.8 5337.6 5383.0 3846.0 3869.8 3874.6 3875.5 3896.9 5474.0 5569.0 5573.8 5644.7 5668.0 3816.4 3828.7 3832.2 3837.4 3844.3 3879.1 3902.0 3902.6 3907.4 3934.8 4521.0 4522.6 4533.1 4537.8 4549.3 5152.8 5337.8 5352.3 5391.3 5402.7 1373.0 1390.1 1391.5 1402.3 1404.8 839.7 853.7 875.6 884.0 894.8 4517.3 4702.1 4707.8 4709.3 4743.7 5555.8 5594.0 5598.9 5602.7 5619.6 4576.7 4643.3 4667.7 4707.1 4736.4 2904.8 2941.7 2949.8 2960.0 2961.0 5170.9 5190.0 5218.8 5231.7 5241.4 5297.6 5645.8 5671.7 5712.9 5738.6 5230.9 5248.0 5264.1 5283.3 5294.2 5548.4 5554.4 5563.5 5565.7 5568.6 5233.5 5430.4 5432.4 5450.0 5454.1 5182.6 5218.8 5246.7 5273.8 5338.6 3807.8 3826.6 3834.8 3834.8 3837.9 5365.9 5470.9 5529.3 5553.4 5554.6 5289.8 5349.7 5368.3 5374.5 5391.6 5249.6 5359.2 5370.3 5398.9 5409.0 5195.3 5214.5 5278.3 5294.6 5297.4 5139.1 5277.8 5452.3 5455.3 5455.6 5225.0 5305.6 5343.9 5359.4 5366.8 06:00:12 INFO - PROCESS | 29184 | 06:00:12 INFO - PROCESS | 29184 | [#5] yui.html Cycles:180 Average:17628.09 Median:21223.59 stddev:25313.60 (119.3%) stddev-sans-first:25352.53 06:00:12 INFO - PROCESS | 29184 | Values: 656.3 693.8 698.0 705.4 760.4 12984.4 13747.0 13808.4 14012.6 14275.3 14791.0 14912.5 14974.2 14994.6 15011.9 628.2 755.0 780.4 794.4 795.9 735.0 771.1 785.3 785.4 785.6 835.6 842.8 844.6 845.5 847.4 830.2 830.5 830.7 831.3 836.0 610.1 610.3 611.5 611.9 612.8 14147.9 14295.1 14304.7 14367.8 14376.7 13385.2 13572.4 13611.3 13637.4 13688.1 10430.1 10493.2 10506.7 10688.3 10750.1 3280.3 3291.9 3328.9 3334.8 3340.8 68141.7 68266.7 68288.3 68814.3 69318.7 9726.6 10155.6 10238.2 10534.2 10589.7 19248.2 19371.8 19379.5 19391.0 19598.6 6794.9 6812.8 6824.4 6907.1 6930.2 3654.9 3665.7 3714.7 3774.4 3787.6 2915.3 3016.4 3029.3 3032.0 3033.6 470.5 484.9 492.9 498.0 500.7 418.7 462.2 462.3 501.0 516.7 7420.1 7445.0 7536.2 7585.8 7665.7 453.3 453.3 456.2 466.7 475.8 3609.4 3667.5 3670.6 3697.3 3725.0 102043.0 102499.0 105491.0 106160.5 106667.0 52310.7 78615.2 86562.1 87887.0 88711.1 50158.7 50348.2 68057.0 74044.9 81446.6 6908.9 6924.6 6991.3 7048.4 7120.5 33423.3 38659.6 46255.5 47263.0 48199.0 53054.5 53811.5 55162.7 62856.7 63750.4 10303.4 10591.5 10876.3 11034.3 11318.7 13060.5 13069.9 13086.4 13146.2 13153.8 11028.7 11076.7 11088.7 11221.7 11454.4 11551.4 11884.8 12415.5 12512.8 13044.3 11585.5 11726.7 11771.1 11880.8 12129.8 9555.5 9718.7 9768.9 9950.9 10275.4 10051.3 10504.3 10513.5 10573.5 10700.4 06:00:12 INFO - PROCESS | 29184 | -------- Summary: end -------- 06:00:12 INFO - PROCESS | 29184 | 06:00:13 INFO - TEST-INFO | 29184: exit 0 06:00:13 INFO - TEST-OK | dromaeo_css | took 1117324ms 06:00:13 INFO - TEST-START | kraken 06:00:13 INFO - operating with platform_type : linux_ 06:00:13 INFO - Initialising browser for kraken test... 06:00:13 INFO - TEST-INFO | started process 29417 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8JXw17/profile http://localhost:36989/getInfo.html) 06:00:18 INFO - PROCESS | 29417 | __metrics Screen width/height:1600/1200 06:00:18 INFO - PROCESS | 29417 | colorDepth:24 06:00:18 INFO - PROCESS | 29417 | Browser inner width/height: 1024/697 06:00:18 INFO - PROCESS | 29417 | __metrics 06:00:18 ERROR - PROCESS | 29417 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 06:00:19 INFO - TEST-INFO | 29417: exit 0 06:00:19 INFO - Browser initialized. 06:00:19 INFO - Running cycle 1/1 for kraken test... 06:00:19 INFO - TEST-INFO | started process 29534 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8JXw17/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:00:31 INFO - PROCESS | 29534 | RSS: Main: 186036224 06:00:31 INFO - PROCESS | 29534 | 06:00:34 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36989/tests/kraken/driver.html?testName=audio-beat-detection) 06:00:35 INFO - PROCESS | 29534 | RSS: Main: 190701568 06:00:35 INFO - PROCESS | 29534 | 06:00:38 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36989/tests/kraken/driver.html?testName=audio-dft) 06:00:38 INFO - PROCESS | 29534 | RSS: Main: 190373888 06:00:38 INFO - PROCESS | 29534 | 06:00:41 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36989/tests/kraken/driver.html?testName=audio-fft) 06:00:42 INFO - PROCESS | 29534 | RSS: Main: 186322944 06:00:42 INFO - PROCESS | 29534 | 06:00:44 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36989/tests/kraken/driver.html?testName=audio-oscillator) 06:00:45 INFO - PROCESS | 29534 | RSS: Main: 187072512 06:00:45 INFO - PROCESS | 29534 | 06:00:47 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36989/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:00:48 INFO - PROCESS | 29534 | RSS: Main: 186871808 06:00:48 INFO - PROCESS | 29534 | 06:00:51 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36989/tests/kraken/driver.html?testName=imaging-darkroom) 06:00:52 INFO - PROCESS | 29534 | RSS: Main: 187588608 06:00:52 INFO - PROCESS | 29534 | 06:00:55 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36989/tests/kraken/driver.html?testName=imaging-desaturate) 06:00:56 INFO - PROCESS | 29534 | RSS: Main: 184463360 06:00:56 INFO - PROCESS | 29534 | 06:00:59 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36989/tests/kraken/driver.html?testName=json-parse-financial) 06:00:59 INFO - PROCESS | 29534 | RSS: Main: 185540608 06:00:59 INFO - PROCESS | 29534 | 06:01:01 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36989/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:01:02 INFO - PROCESS | 29534 | RSS: Main: 186351616 06:01:02 INFO - PROCESS | 29534 | 06:01:04 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36989/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:01:04 INFO - PROCESS | 29534 | RSS: Main: 186912768 06:01:04 INFO - PROCESS | 29534 | 06:01:06 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36989/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:01:07 INFO - PROCESS | 29534 | RSS: Main: 186990592 06:01:07 INFO - PROCESS | 29534 | 06:01:10 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:01:11 INFO - PROCESS | 29534 | RSS: Main: 186699776 06:01:11 INFO - PROCESS | 29534 | 06:01:14 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:01:14 INFO - PROCESS | 29534 | RSS: Main: 186683392 06:01:14 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | Cycle 1(1): loaded http://localhost:36989/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36989/tests/kraken/driver.html?testName=ai-astar) 06:01:16 INFO - PROCESS | 29534 | RSS: Main: 187502592 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | __start_tp_report 06:01:16 INFO - PROCESS | 29534 | _x_x_mozilla_page_load 06:01:16 INFO - PROCESS | 29534 | _x_x_mozilla_page_load_details 06:01:16 INFO - PROCESS | 29534 | |i|pagename|runs| 06:01:16 INFO - PROCESS | 29534 | |0;ai-astar;116;107;114;115;115;113;114;112;112;113 06:01:16 INFO - PROCESS | 29534 | |1;audio-beat-detection;164;132;169;181;133;164;164;133;194;141 06:01:16 INFO - PROCESS | 29534 | |2;audio-dft;164;165;173;167;163;163;162;163;163;165 06:01:16 INFO - PROCESS | 29534 | |3;audio-fft;78;77;79;162;77;79;76;78;158;76 06:01:16 INFO - PROCESS | 29534 | |4;audio-oscillator;88;87;142;86;87;88;140;86;88;134 06:01:16 INFO - PROCESS | 29534 | |5;imaging-gaussian-blur;118;119;118;117;118;118;121;118;118;119 06:01:16 INFO - PROCESS | 29534 | |6;imaging-darkroom;147;149;149;148;159;148;147;150;149;147 06:01:16 INFO - PROCESS | 29534 | |7;imaging-desaturate;96;96;95;96;96;97;95;95;96;95 06:01:16 INFO - PROCESS | 29534 | |8;json-parse-financial;64;64;63;65;64;65;64;63;63;64 06:01:16 INFO - PROCESS | 29534 | |9;json-stringify-tinderbox;71;66;64;66;66;65;65;65;70;66 06:01:16 INFO - PROCESS | 29534 | |10;stanford-crypto-aes;84;85;87;84;86;85;87;86;112;85 06:01:16 INFO - PROCESS | 29534 | |11;stanford-crypto-ccm;139;145;134;149;135;130;152;155;146;142 06:01:16 INFO - PROCESS | 29534 | |12;stanford-crypto-pbkdf2;176;171;172;170;171;172;171;169;171;173 06:01:16 INFO - PROCESS | 29534 | |13;stanford-crypto-sha256-iterative;70;70;69;68;76;68;67;68;68;67 06:01:16 INFO - PROCESS | 29534 | __end_tp_report 06:01:16 INFO - PROCESS | 29534 | __start_cc_report 06:01:16 INFO - PROCESS | 29534 | _x_x_mozilla_cycle_collect,969 06:01:16 INFO - PROCESS | 29534 | __end_cc_report 06:01:16 INFO - PROCESS | 29534 | __startTimestamp1466082076721__endTimestamp 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | ------- Summary: start ------- 06:01:16 INFO - PROCESS | 29534 | Number of tests: 14 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:2.51 (2.2%) stddev-sans-first:2.44 06:01:16 INFO - PROCESS | 29534 | Values: 116.0 107.0 114.0 115.0 115.0 113.0 114.0 112.0 112.0 113.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#1] audio-beat-detection Cycles:10 Average:157.50 Median:164.00 stddev:21.77 (13.3%) stddev-sans-first:22.97 06:01:16 INFO - PROCESS | 29534 | Values: 164.0 132.0 169.0 181.0 133.0 164.0 164.0 133.0 194.0 141.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#2] audio-dft Cycles:10 Average:164.80 Median:164.50 stddev:3.22 (2.0%) stddev-sans-first:3.41 06:01:16 INFO - PROCESS | 29534 | Values: 164.0 165.0 173.0 167.0 163.0 163.0 162.0 163.0 163.0 165.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#3] audio-fft Cycles:10 Average:94.00 Median:77.50 stddev:34.81 (44.9%) stddev-sans-first:36.44 06:01:16 INFO - PROCESS | 29534 | Values: 78.0 77.0 79.0 162.0 77.0 79.0 76.0 78.0 158.0 76.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#4] audio-oscillator Cycles:10 Average:102.60 Median:87.00 stddev:24.98 (28.7%) stddev-sans-first:25.93 06:01:16 INFO - PROCESS | 29534 | Values: 88.0 87.0 142.0 86.0 87.0 88.0 140.0 86.0 88.0 134.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#5] imaging-gaussian-blur Cycles:10 Average:118.40 Median:118.00 stddev:1.07 (0.9%) stddev-sans-first:1.13 06:01:16 INFO - PROCESS | 29534 | Values: 118.0 119.0 118.0 117.0 118.0 118.0 121.0 118.0 118.0 119.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#6] imaging-darkroom Cycles:10 Average:149.30 Median:149.00 stddev:3.56 (2.4%) stddev-sans-first:3.68 06:01:16 INFO - PROCESS | 29534 | Values: 147.0 149.0 149.0 148.0 159.0 148.0 147.0 150.0 149.0 147.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 06:01:16 INFO - PROCESS | 29534 | Values: 96.0 96.0 95.0 96.0 96.0 97.0 95.0 95.0 96.0 95.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#8] json-parse-financial Cycles:10 Average:63.90 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 06:01:16 INFO - PROCESS | 29534 | Values: 64.0 64.0 63.0 65.0 64.0 65.0 64.0 63.0 63.0 64.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#9] json-stringify-tinderbox Cycles:10 Average:66.40 Median:66.00 stddev:2.27 (3.4%) stddev-sans-first:1.69 06:01:16 INFO - PROCESS | 29534 | Values: 71.0 66.0 64.0 66.0 66.0 65.0 65.0 65.0 70.0 66.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:85.50 stddev:8.46 (9.9%) stddev-sans-first:8.85 06:01:16 INFO - PROCESS | 29534 | Values: 84.0 85.0 87.0 84.0 86.0 85.0 87.0 86.0 112.0 85.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#11] stanford-crypto-ccm Cycles:10 Average:142.70 Median:145.50 stddev:8.19 (5.6%) stddev-sans-first:8.58 06:01:16 INFO - PROCESS | 29534 | Values: 139.0 145.0 134.0 149.0 135.0 130.0 152.0 155.0 146.0 142.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:171.50 stddev:1.90 (1.1%) stddev-sans-first:1.17 06:01:16 INFO - PROCESS | 29534 | Values: 176.0 171.0 172.0 170.0 171.0 172.0 171.0 169.0 171.0 173.0 06:01:16 INFO - PROCESS | 29534 | 06:01:16 INFO - PROCESS | 29534 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:68.50 stddev:2.64 (3.9%) stddev-sans-first:2.78 06:01:16 INFO - PROCESS | 29534 | Values: 70.0 70.0 69.0 68.0 76.0 68.0 67.0 68.0 68.0 67.0 06:01:16 INFO - PROCESS | 29534 | -------- Summary: end -------- 06:01:16 INFO - PROCESS | 29534 | 06:01:17 INFO - TEST-INFO | 29534: exit 0 06:01:18 INFO - TEST-OK | kraken | took 64870ms 06:01:18 INFO - SUITE-END | took 1182s 06:01:18 INFO - Completed test suite (00:19:42) 06:01:18 INFO - javascript benchmark 06:01:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6476.246308571486, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2647.139679604129, 2701.9054333098347, 2704.2698471412705, 2707.4855777402304, 2718.605802158686, 4791.448983366914, 4814.783334749936, 4817.421909370876, 4888.926666100011, 4927.384076990376, 4748.762561871901, 4829.492903245153, 4877.561019508244, 4915.967646794169, 4939.506049395068, 4421.248387774078, 4440.711353761995, 4522.253828118356, 4553.5901768840795, 4559.17934771741, 4237.216115018716, 4521.728696278217, 4522.8643140705835, 4606.493285738566, 4609.354690343354, 4436.3123715824095, 4445.377647129404, 4453.398791163217, 4486.887827804331, 4552.11233809404, 4405.096955124224, 4411.316245981878, 4476.462824461053, 4497.302918047708, 4518.683692141552, 4453.376527286183, 4488.932666010012, 4499.03270796777, 4505.504394516601, 4530.977345113286, 4801.807927682923, 4850.248211527219, 4881.926771098437, 4896.192128298826, 4911.656184067117, 5203.531682148625, 5210.505002024802, 5216.113260745682, 5242.056429842627, 5261.053010458116, 5145.434002259786, 5250.816221432232, 5274.0, 5283.128283833163, 5285.762140703678, 4876.439209490883, 4928.433230178575, 4932.334134891744, 4953.479884612064, 4986.202207646759, 5398.973005135028, 5413.133898576288, 5421.566274698014, 5422.53908417781, 5464.699441530674, 5065.088284108896, 5162.174052151638, 5192.4547922468355, 5197.090509160882, 5200.7919683213, 5180.248863914711, 5188.351456067991, 5312.893742125136, 5321.547668448148, 5374.838754837283, 5151.22731590257, 5187.455317191716, 5190.740462976836, 5208.479152084761, 5217.452167522356, 5063.291139240436, 5127.66670166438, 5148.433672296044, 5192.299039629677, 5258.7370631468275, 4365.279728844768, 4388.71473354231, 4390.253656878338, 4415.359772832921, 4427.203103441347, 3810.8666196682984, 3831.8084095795098, 3833.290841194388, 3861.7876016819346, 3881.2819628368843, 4473.731576105444, 4480.238359478896, 4515.322701594721, 4564.475085365226, 4609.193391156534, 5240.266362709223, 5280.0, 5295.947040529545, 5300.840974770763, 5352.090144675414, 4535.206338890681, 4549.135664223787, 4554.88612784683, 4559.6808223424705, 4599.563041511051, 4568.291914753218, 4638.141943740419, 4653.7207767534055, 4739.905201896012, 4781.091592597395, 4896.4124305083615, 4909.361782968192, 4929.383827021622, 4932.161532539405, 4967.205247160509, 5212.0, 5229.372475302988, 5229.660072095302, 5237.319148510669, 5242.0, 4825.95174048255, 4915.3855768029, 4918.458969513422, 4940.357753492167, 4992.550670439678, 5223.373195216598, 5233.973830130825, 5237.947620523746, 5238.371395432572, 5259.658122222043, 5252.395974462986, 5268.209768534751, 5269.077911365573, 5271.051210782096, 5327.413984461784, 4963.553280204799, 4983.103041452573, 5025.195968644999, 5049.57078648319, 5081.0, 5027.296178534935, 5048.167052436307, 5071.568916642127, 5072.67027643202, 5130.97434512825, 4961.578265847299, 4982.153033984159, 4992.400911890596, 5014.147594908802, 5023.447420783637, 4967.503249675148, 5100.2349647553165, 5128.205128205134, 5148.742562871917, 5167.509086636764, 5012.172991456368, 5046.369203849519, 5061.721605311596, 5078.08594453002, 5107.821226257063], "value": 4798.381577105844, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.9521490673293, 578.3984655957803, 586.129597547642, 598.7964092208651, 606.3421188011008, 35261.47107793385, 38974.0, 39014.21971560567, 39034.0, 39036.41445378322, 38478.61521384785, 39257.21485570287, 39272.0, 39376.409353859715, 39380.0, 7742.8451430971345, 7956.562389068599, 7960.203979602052, 7960.6019699015105, 7962.960185199066, 7848.68605255789, 7893.289603935645, 7913.248241417056, 7913.723019694312, 7933.0, 7855.332296754754, 7866.370690344787, 7868.2918537331625, 7873.291403773688, 7919.604019799016, 7837.2554607312495, 7860.056793184797, 7878.09401918781, 7891.881621775678, 7905.96047019767, 7796.766097017042, 7928.0, 7934.127246002935, 7943.086545047336, 7960.1243863175005, 36628.450573241425, 36916.81541592275, 37302.0, 37380.25239495222, 37538.43692344617, 2958.156925276286, 3069.2326918270433, 3346.665333466658, 3602.423612222058, 3755.286495565834, 29864.850675746482, 30013.099607011824, 30134.0, 30148.397032059456, 30376.392472150656, 3547.8935631931085, 3622.4385220290897, 3625.764325318872, 3689.981550092259, 3761.6614504694708, 241550.0, 242482.0, 242925.0, 243205.0, 244174.0, 31399.21501962469, 32270.19324516906, 32352.83823580867, 34232.65767342345, 51836.48163518317, 33792.0, 33836.32327353489, 33912.49131263033, 34054.65945340515, 34144.0, 11679.065674746, 11730.472128754227, 11771.764564708828, 11775.882241177478, 11818.054555635528, 9587.664431744855, 9607.375520591138, 9612.471314077653, 9700.805983880424, 9783.559739811726, 7873.763787086396, 7879.251471110236, 7974.920250797418, 8016.358691304682, 8041.6381262843315, 3113.517404802259, 3132.3265497918055, 3143.717065464119, 3202.5676533668125, 3208.8876889308763, 856.4261944497201, 869.9391042627082, 919.0, 966.3042609321277, 1005.7435353984687, 28261.57607635887, 28321.858390708327, 28744.0, 28766.71233287682, 29156.562651560245, 872.5026734761125, 948.2414068744977, 962.8170647576937, 1036.4765793274348, 1084.0297933349607, 14562.781558276634, 14591.708165836624, 14664.266786660624, 14910.552683419515, 15046.172460514777, 407760.0, 408348.0, 408984.0, 409219.0, 410346.0, 49811.50565483041, 50881.0, 51014.48985510172, 51035.23447148296, 51111.23333150006, 1132.184826924613, 1133.9092872570175, 1147.1453766943514, 1171.00464605085, 1197.383347575999, 26416.73583264143, 26564.468710625293, 27094.593581095905, 27270.72729272682, 27375.726242737317, 42015.7899210502, 42059.579404206794, 42205.0, 42236.155276894904, 42333.788331058146, 337263.0, 340552.0, 343429.0, 344218.0, 344504.0, 25028.499430011663, 25161.0, 25326.746732533175, 25733.0, 25883.482330352912, 40847.38728919128, 41094.1781164381, 41971.16057678768, 42084.36873446962, 42090.57909420989, 36101.27797444089, 36163.457548137325, 36294.0, 36557.817210913774, 36745.0, 35339.64660353467, 35505.1123721909, 35704.64295357117, 35889.82055089813, 35978.64021359753, 34182.487262690585, 35233.29533409369, 35284.0, 35932.820335898155, 36314.27371452609, 31169.0, 32988.88672255804, 33125.503117453736, 33219.0, 33316.0, 25322.90015847846, 25611.871940640176, 25684.614730779427, 25692.614610780478, 25902.222933311277], "value": 16327.97401311381, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.4575114452749, 368.52092280035953, 370.22622718518306, 376.55002272284594, 388.2119297825415, 15389.538313850588, 16787.74818377724, 17611.73582396262, 17643.735343969835, 17668.38160664377, 16047.117408542526, 16419.0, 17144.914275428604, 17170.82829171708, 17190.484285471426, 5131.717755523454, 5230.7646155922885, 5277.70972596507, 5324.281222035014, 5329.520343169114, 3759.774413535197, 5152.046871328797, 5874.588778785486, 6257.155284036642, 6259.499240060807, 6181.165542651729, 6216.658083805389, 6281.152044473984, 6299.622022678631, 6326.240851097875, 6153.7846175383875, 6299.559030867796, 6304.05439184126, 6307.0, 6325.1777268954975, 5534.252875861747, 5559.388467268597, 5567.693776842272, 5583.944160558383, 5608.9439105609235, 20471.38585842427, 20699.0, 21479.785202147934, 21485.67771483429, 21490.785092149035, 1897.089397089391, 1911.2259534888392, 1928.1227041695995, 1939.8545109116872, 1950.2296592845746, 13287.335633218301, 13406.0, 13431.261280629562, 13634.590962271148, 14055.578332650037, 1916.281394477071, 1965.4202010406964, 1978.3768113044346, 1984.8908310042941, 2005.809448102428, 72736.6363168186, 75890.0, 76431.23568764297, 76620.23379766187, 77422.61288693521, 15917.761233581505, 16125.95181313211, 16211.432599859185, 16481.25834337458, 16583.17084145788, 27691.86154069257, 27956.161315160578, 27991.300217494725, 28012.85993570019, 28087.297817554725, 8033.879491807627, 8080.959595201986, 8206.261436470746, 8450.619722112397, 8481.109483504266, 6509.804705858832, 6566.080748695186, 6589.308122647149, 6820.829479262959, 6830.692618832164, 8374.728888267027, 8378.58107094655, 8441.0, 8518.403711740242, 8675.479471231747, 1548.8141423029308, 1564.7261729197344, 1584.8890577659454, 1620.4085508789442, 1623.6103335199466, 859.4542465534431, 894.6466145872344, 899.9820003600022, 924.9039887733057, 930.3069213435898, 16113.677726445405, 16359.83640163607, 16547.420840270534, 16689.415870444474, 16794.580135496464, 832.8417600655856, 843.4559708987669, 859.7576501954741, 867.2758246863939, 870.3950754225752, 9399.90600094004, 9417.90582094184, 9463.337566370288, 9645.469499177472, 9788.853167202498, 378646.0, 380673.0, 383180.0, 384737.0, 384955.0, 77071.0, 77567.61216193883, 77992.61003694945, 79591.60204198942, 79946.6002669983, 931.0875342164582, 941.0730430525927, 947.7535840681472, 951.0204489376092, 953.413650604873, 15155.469558565397, 15270.541883743506, 15420.537383878502, 15481.148536830582, 15520.0, 38421.4236786454, 38450.230995379374, 38917.0, 40522.1895562093, 40845.1830963385, 53242.73378633082, 53826.0, 54813.0, 54943.45056549383, 56187.0, 7582.469227154046, 7680.846383072419, 7704.576248306397, 7749.651265692944, 7872.488288261245, 8116.107228204774, 8177.67289308421, 8343.332533397435, 8387.287080598218, 8402.41183117176, 7366.373858222111, 7424.554526728413, 7574.431917606092, 7597.696092156473, 7718.845623087619, 7299.233580473974, 7418.36943859778, 7622.961885190539, 7638.579878106758, 7884.645190966306, 7565.6973721050545, 7617.238276172338, 7685.0, 7809.0, 7948.682052717826, 10662.573497060032, 10707.732306692084, 10850.728731781768, 10922.672319830417, 11036.944815275872, 10545.894541054799, 10847.891521084688, 11175.105991520497, 11215.0, 11350.716232094264], "value": 8718.314693997181, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1387.0360099730688, 1415.8159439272893, 1462.9707405851884, 1662.4513910409567, 1672.3059930128998, 6980.895286570705, 7620.923790762091, 7737.922620773805, 8179.141190175028, 8210.0, 7693.615319234043, 7933.722319718811, 8005.359571234302, 8033.357331413487, 8038.155993620659, 2189.211883721859, 2241.4172315198084, 2241.9887900560475, 2243.6410174372104, 2248.7301523817164, 2183.7161169048, 2193.7915897989747, 2203.019656252968, 2207.536417352358, 2215.6898034275214, 2139.1657253671083, 2155.9353219403365, 2161.492049368397, 2167.306461932182, 2195.407240045187, 2100.69539916712, 2111.4721319670084, 2112.8309735221146, 2113.5244569971633, 2120.1943261560587, 2106.8946552672305, 2128.957420851574, 2131.05168200151, 2144.1852096203497, 2152.2467136502255, 7975.282224599814, 8067.233612806774, 8076.757697269091, 8107.797305067362, 8138.308243799284, 4357.891052723676, 4437.312216606431, 4457.0, 4461.553844615512, 4465.977670111661, 2836.120802551195, 2920.6057182280333, 2956.9852150739107, 2966.362232120083, 2969.6584892737396, 858.6822875536029, 875.614729519016, 880.0143838900409, 889.4929889962724, 905.3210092430677, 11131.109511239081, 11162.497687604076, 11167.329960202414, 11170.497327620276, 11184.44077796107, 4136.710430269853, 4192.538820729717, 4225.2817021105875, 4252.766097864647, 4276.743395396286, 9314.90685093154, 9365.344425890122, 9378.624855005859, 9380.343375963617, 9394.718158455258, 4561.977190114028, 4581.5647513486165, 4583.679142459996, 4587.334836448763, 4593.494715581351, 3576.481410195559, 3981.024648961023, 4100.569440208795, 4155.501339839178, 4183.790810459464, 3618.8914332570066, 3634.7637403568683, 3711.350513660099, 3742.419924911643, 3780.017195529127, 1194.9701257468632, 1205.7528206717602, 1225.197495640357, 1227.1287385955893, 1227.9570215042431, 772.9574873381905, 784.2157842157842, 811.5009269299456, 816.4488969945263, 835.3609488741118, 4967.3542439482635, 5062.316587260672, 5243.0, 5259.368875734936, 5314.813981510629, 773.7446642607925, 816.4774544291658, 856.1524091149842, 861.1087524412203, 864.6757465950269, 3413.709834664032, 3489.7731647443343, 3543.167355671415, 3630.0380399194237, 3663.9084022899106, 9717.56270967808, 9995.550200241007, 10008.09927106564, 10073.748156296151, 10076.596936122472, 10031.297809153435, 10032.749181270527, 10042.14641755459, 10051.04515071067, 10076.798464030679, 7284.053073100463, 7360.411167106726, 7403.962980185064, 7452.627368631655, 7498.775036748906, 4953.23224900141, 4971.279164521196, 4995.550400463976, 5012.8496145115705, 5030.547250747451, 8882.600282987152, 8902.910970890467, 8932.821343573221, 9127.908720912706, 9160.404573702688, 9002.729918102468, 9083.0, 9230.0, 9230.953845230732, 9298.442093474414, 5943.0, 6242.0, 6249.375062493714, 6289.96855015722, 6301.684915754286, 7568.8486230276185, 7617.200193979553, 7658.119316278699, 7682.308592226726, 7701.306882380389, 6498.967505162444, 6831.077804496329, 6882.827929301808, 6905.309469053255, 7110.39561637246, 6769.627670478171, 6794.898076528757, 6800.727970881308, 6892.414144797748, 6908.896366554405, 6240.968795155995, 6289.308176100534, 6300.779472718433, 6321.905171422341, 6349.07938348946, 4381.912361752811, 4438.733675979452, 4506.932396013997, 4520.344550040291, 4551.544845515422, 4517.073999829961, 4534.818607255805, 4583.679142459996, 4593.977030114828, 4607.447106347126], "value": 4139.6721257124345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.3677924073621, 296.93912747886685, 298.7490507973302, 299.8965356951851, 302.1253471200875, 5106.795728170873, 5133.0, 5166.328377310953, 5172.0948833954, 5181.196914478252, 5058.5953123750105, 5096.898062038757, 5127.512886275807, 5134.25553294773, 5160.174372100455, 3704.5554533456025, 3862.0, 3955.9011024724323, 3960.841566337343, 3986.9601303987024, 3882.9223415531674, 3924.3721004639265, 3952.565217826037, 3967.444557761916, 4010.11777408971, 3970.166265084336, 3981.1042515434087, 3984.3027470192746, 3993.7004724645767, 4002.679115891763, 3781.0, 3932.1939002504705, 3953.920921581581, 3970.9873982134513, 3989.501312335958, 3418.85468368097, 3446.7070299024613, 3454.4300190468543, 3454.481827725836, 3457.9827100864336, 5000.499950005007, 5021.673591216596, 5026.371703537058, 5030.874228144289, 5041.571466425358, 4386.151810798896, 4524.389207456984, 4553.567411095941, 4553.635709143261, 4554.223852697451, 5293.602979776532, 5315.3621565412395, 5325.7869685212545, 5337.599680024014, 5383.0, 3846.038520354611, 3869.7616762635967, 3874.5544262409903, 3875.5466699987505, 3896.902577435558, 5473.987312347229, 5568.969740598002, 5573.804916827891, 5644.74598643062, 5668.0, 3816.358965644744, 3828.693704503633, 3832.1952389998414, 3837.424386342015, 3844.2888065707934, 3879.1271963807917, 3902.0, 3902.648761611412, 3907.355286377716, 3934.8229329680235, 4521.0, 4522.638188944877, 4533.138703646297, 4537.795799189044, 4549.294859296814, 5152.845414637567, 5337.759800808973, 5352.277442545284, 5391.272178255964, 5402.729863506809, 1372.9519466818613, 1390.0686540018212, 1391.4503771010395, 1402.2778269191374, 1404.7778432763562, 839.7228914458215, 853.7182729699184, 875.59722527638, 883.9911600884037, 894.8478758611052, 4517.34498497716, 4702.059588082398, 4707.835225767081, 4709.270063140219, 4743.66794324394, 5555.805546805843, 5594.049011668027, 5598.888022239614, 5602.747876345574, 5619.550435965113, 4576.748278844629, 4643.2802915548145, 4667.743274119888, 4707.129181101507, 4736.360591320196, 2904.8257104573795, 2941.6617089035035, 2949.7640188784844, 2959.978807311474, 2961.0228624553847, 5170.870728231825, 5189.974050129726, 5218.817341393109, 5231.659942103751, 5241.397239317527, 5297.649099479608, 5645.774169033275, 5671.688057156814, 5712.885742285131, 5738.598298119092, 5230.869228269324, 5248.0, 5264.131418316011, 5283.313169288045, 5294.232336311136, 5548.363232846401, 5554.389017208185, 5563.471470210324, 5565.666060036411, 5568.554515638658, 5233.476652334736, 5430.375506816767, 5432.402435731988, 5449.972750136224, 5454.072807622634, 5182.559482444034, 5218.799676074448, 5246.711430871339, 5273.815416460559, 5338.599605029561, 3807.7715337079862, 3826.5790763016603, 3834.8082595869537, 3834.8082595869537, 3837.884863454101, 5365.946340536545, 5470.8905821882545, 5529.281193444987, 5553.389127196086, 5554.638948468337, 5289.814856480005, 5349.705766182898, 5368.2752828367675, 5374.468276541167, 5391.62258641907, 5249.580033597226, 5359.249705041375, 5370.275012873212, 5398.865028374322, 5408.998670491943, 5195.272661827423, 5214.452482489284, 5278.287431196739, 5294.576433885357, 5297.390800057889, 5139.07284768212, 5277.788888444419, 5452.318460192476, 5455.263539422286, 5455.5908306876345, 5224.9738751306, 5305.575553955597, 5343.946560534345, 5359.4104648487855, 5366.758495867617], "value": 4024.7826080469254, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.2977613953069, 693.8161387232383, 698.0422097398703, 705.3686950179589, 760.3954855889567, 12984.415701293441, 13747.0, 13808.378622961978, 14012.64968375791, 14275.286235688225, 14791.0, 14912.478063267788, 14974.176420296879, 14994.550163495085, 15011.924940375282, 628.2304177382692, 754.9584772837492, 780.4302858912997, 794.3922898982283, 795.8806179073157, 735.0114096540145, 771.1093686791736, 785.3285440947993, 785.4227143049853, 785.6111224943661, 835.6448509383519, 842.8356470488227, 844.5945945945949, 845.4589062999685, 847.3729440214228, 830.1739768929954, 830.5182993863605, 830.6926437218237, 831.2560258568548, 836.0051538668964, 610.1305639463747, 610.2707264818544, 611.4649681528663, 611.8500967263004, 612.7548980407828, 14147.858521414757, 14295.142291462442, 14304.713905721932, 14367.78448323276, 14376.712465750627, 13385.19688818674, 13572.389242484112, 13611.319434028259, 13637.386317615732, 13688.110272832235, 10430.113440357578, 10493.213009024277, 10506.684799456028, 10688.305260158142, 10750.086242669382, 3280.2947366316116, 3291.8683252669866, 3328.8834890778708, 3334.7999120052873, 3340.816255105968, 68141.65929170372, 68266.65866670635, 68288.31711682919, 68814.3118568813, 69318.65340673315, 9726.562304696306, 10155.593776249016, 10238.232132590085, 10534.157267418588, 10589.682309530726, 19248.23007079729, 19371.806281937283, 19379.546534009725, 19391.0, 19598.608027839364, 6794.932050679528, 6812.795616131524, 6824.4199243064895, 6907.102076729993, 6930.203026652, 3654.908627284286, 3665.6884164846288, 3714.7028237740915, 3774.433834924728, 3787.5644300905747, 2915.3149009982635, 3016.4117996990817, 3029.348690031654, 3032.0297504798255, 3033.5601337805892, 470.5318208382681, 484.9030193961222, 492.9186684197139, 498.01890276155916, 500.6620531141465, 418.7089972469105, 462.21192866163113, 462.26269100784197, 500.96743711658627, 516.657227736544, 7420.109586849612, 7444.952638024586, 7536.171021187665, 7585.848283034308, 7665.693372265158, 453.26570955052364, 453.32454642582974, 456.1766012347678, 466.6709969471514, 475.8239451403003, 3609.4405367168133, 3667.5232219811787, 3670.559532856021, 3697.279030589063, 3725.0, 102043.0, 102499.0, 105491.0, 106160.46919765351, 106667.0, 52310.73844630752, 78615.2138478608, 86562.13437865667, 87887.0, 88711.11288887028, 50158.749206253735, 50348.24477632912, 68057.0, 74044.88932666014, 81446.59276703579, 6908.93091069103, 6924.58452492852, 6991.3008699131715, 7048.365647091787, 7120.465965052538, 33423.33153336968, 38659.6134038656, 46255.53744462512, 47263.0, 48199.0, 53054.4694553065, 53811.461885380646, 55162.72418637881, 62856.685716571126, 63750.3624963763, 10303.433311167957, 10591.523381448007, 10876.347419154876, 11034.282771619819, 11318.717032074264, 13060.542880999119, 13069.934650327068, 13086.411111499814, 13146.21122732639, 13153.802692959422, 11028.724281893017, 11076.667699968692, 11088.667339979813, 11221.719457013314, 11454.370009649216, 11551.422428878355, 11884.821727674265, 12415.503379864704, 12512.812307815208, 13044.282564459047, 11585.478653460783, 11726.70683232926, 11771.117166212398, 11880.821787673365, 12129.818052729393, 9555.522223888916, 9718.659846905324, 9768.85346719814, 9950.850737239092, 10275.434851082962, 10051.346662466916, 10504.26470147113, 10513.526891289757, 10573.52419141156, 10700.41147736882], "value": 6482.28003478292, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1597.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 107.0, 114.0, 115.0, 115.0, 113.0, 114.0, 112.0, 112.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 132.0, 169.0, 181.0, 133.0, 164.0, 164.0, 133.0, 194.0, 141.0], "value": 157.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 173.0, 167.0, 163.0, 163.0, 162.0, 163.0, 163.0, 165.0], "value": 164.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 79.0, 162.0, 77.0, 79.0, 76.0, 78.0, 158.0, 76.0], "value": 94.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 142.0, 86.0, 87.0, 88.0, 140.0, 86.0, 88.0, 134.0], "value": 102.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 119.0, 118.0, 117.0, 118.0, 118.0, 121.0, 118.0, 118.0, 119.0], "value": 118.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 149.0, 148.0, 159.0, 148.0, 147.0, 150.0, 149.0, 147.0], "value": 149.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 96.0, 97.0, 95.0, 95.0, 96.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 63.0, 65.0, 64.0, 65.0, 64.0, 63.0, 63.0, 64.0], "value": 63.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 66.0, 64.0, 66.0, 66.0, 65.0, 65.0, 65.0, 70.0, 66.0], "value": 66.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 87.0, 84.0, 86.0, 85.0, 87.0, 86.0, 112.0, 85.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 145.0, 134.0, 149.0, 135.0, 130.0, 152.0, 155.0, 146.0, 142.0], "value": 142.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 171.0, 172.0, 170.0, 171.0, 172.0, 171.0, 169.0, 171.0, 173.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 68.0, 76.0, 68.0, 67.0, 68.0, 68.0, 67.0], "value": 69.1, "unit": "score"}]}]} 06:01:18 INFO - Return code: 0 06:01:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:01:18 ERROR - # TBPL SUCCESS # 06:01:18 INFO - Running post-action listener: _resource_record_post_action 06:01:18 INFO - ##### 06:01:18 INFO - ##### Finished run-tests step (success) 06:01:18 INFO - ##### 06:01:18 INFO - Running post-run listener: _resource_record_post_run 06:01:18 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 0; Write bytes: 544325632; Read time: 0; Write time: 550380 06:01:18 INFO - TinderboxPrint: CPU usage
13.4% 06:01:18 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 06:01:18 INFO - TinderboxPrint: I/O write bytes / time
544,325,632 / 550,380 06:01:18 INFO - TinderboxPrint: CPU idle
8,284.5 (86.5%) 06:01:18 INFO - TinderboxPrint: CPU user
1,230.3 (12.9%) 06:01:18 INFO - TinderboxPrint: Swap in / out
0 / 0 06:01:18 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 245702656; Read time: 0; Write time: 361500 06:01:18 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 298487808; Read time: 0; Write time: 188864 06:01:19 INFO - Running post-run listener: _upload_blobber_files 06:01:19 INFO - Blob upload gear active. 06:01:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:01:19 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 06:01:19 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:01:19 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:01:19 INFO - (blobuploader) - INFO - Open directory for files ... 06:01:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:01:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:01:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:20 INFO - (blobuploader) - INFO - Done attempting. 06:01:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:01:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:01:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:24 INFO - (blobuploader) - INFO - Done attempting. 06:01:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:01:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:01:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:01:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:01:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:01:25 INFO - (blobuploader) - INFO - Done attempting. 06:01:25 INFO - (blobuploader) - INFO - Iteration through files over. 06:01:25 INFO - Return code: 0 06:01:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:01:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:01:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f7b1836dbbd66c6fd2a6a44422aa6ebdfc92ddd0c3aaa90dfbfe42ad2aa3112e09717c18772d7b2a00d6e456fcbc9e39ec887aebe2f2548d585eb13dda1736ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eebc21e95b31e30aee3e8c7ec498b151795a4597797537027f846123728a1f37332d61287d0e003678c9e2fadead8c910481764520736fbcd49df77503789ee2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e78cee09f80c7096a68c8fcc76b78eb59586955e9f06d4a1d9e245bfa156c105ca19d7a0c36dba36eedab79fbfff038831493d72ecbf806b06cfa0b8f4ac0bf4"} 06:01:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:01:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:01:25 INFO - Contents: 06:01:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f7b1836dbbd66c6fd2a6a44422aa6ebdfc92ddd0c3aaa90dfbfe42ad2aa3112e09717c18772d7b2a00d6e456fcbc9e39ec887aebe2f2548d585eb13dda1736ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eebc21e95b31e30aee3e8c7ec498b151795a4597797537027f846123728a1f37332d61287d0e003678c9e2fadead8c910481764520736fbcd49df77503789ee2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e78cee09f80c7096a68c8fcc76b78eb59586955e9f06d4a1d9e245bfa156c105ca19d7a0c36dba36eedab79fbfff038831493d72ecbf806b06cfa0b8f4ac0bf4"} 06:01:25 INFO - Running post-run listener: copy_logs_to_upload_dir 06:01:25 INFO - Copying logs to upload dir... 06:01:25 INFO - mkdir: /builds/slave/test/build/upload/logs 06:01:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.180938 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-06-16 06:01:22.706535) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-16 06:01:22.709912) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255 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/Fx-Team-Non-PGO/sha512/f7b1836dbbd66c6fd2a6a44422aa6ebdfc92ddd0c3aaa90dfbfe42ad2aa3112e09717c18772d7b2a00d6e456fcbc9e39ec887aebe2f2548d585eb13dda1736ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eebc21e95b31e30aee3e8c7ec498b151795a4597797537027f846123728a1f37332d61287d0e003678c9e2fadead8c910481764520736fbcd49df77503789ee2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e78cee09f80c7096a68c8fcc76b78eb59586955e9f06d4a1d9e245bfa156c105ca19d7a0c36dba36eedab79fbfff038831493d72ecbf806b06cfa0b8f4ac0bf4"} build_url:https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011258 build_url: 'https://queue.taskcluster.net/v1/task/PP_y5hEoQ52kEEqMilp7rA/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/Fx-Team-Non-PGO/sha512/f7b1836dbbd66c6fd2a6a44422aa6ebdfc92ddd0c3aaa90dfbfe42ad2aa3112e09717c18772d7b2a00d6e456fcbc9e39ec887aebe2f2548d585eb13dda1736ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/eebc21e95b31e30aee3e8c7ec498b151795a4597797537027f846123728a1f37332d61287d0e003678c9e2fadead8c910481764520736fbcd49df77503789ee2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e78cee09f80c7096a68c8fcc76b78eb59586955e9f06d4a1d9e245bfa156c105ca19d7a0c36dba36eedab79fbfff038831493d72ecbf806b06cfa0b8f4ac0bf4"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-16 06:01:22.735814) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 06:01:22.736155) ========= 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=97facb8ba2ac28944cc45e6b000001d4-1466074318.710153-1068548255 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-16 06:01:22.820282) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-16 06:01:22.820920) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-16 06:01:22.821457) ========= ========= Total master_lag: 0.15 =========