builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-054 starttime: 1474363488.04 results: success (0) buildid: 20160920003916 builduid: 172665a53b3746cd894d45ae120de9f0 revision: 0750ccffec1e67c071071fe053acde164b53aa1d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:48.037286) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:48.037906) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:48.038226) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:48.053307) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:48.053605) ========= 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=cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-20 02:24:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 37.7M=0s 2016-09-20 02:24:48 (37.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102384 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:48.170702) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:48.171059) ========= 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=cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015975 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:48.221020) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 02:24:48.221427) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0750ccffec1e67c071071fe053acde164b53aa1d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0750ccffec1e67c071071fe053acde164b53aa1d --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=cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-20 02:24:48,414 truncating revision to first 12 chars 2016-09-20 02:24:48,415 Setting DEBUG logging. 2016-09-20 02:24:48,415 attempt 1/10 2016-09-20 02:24:48,415 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0750ccffec1e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-20 02:24:49,744 unpacking tar archive at: mozilla-beta-0750ccffec1e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.667214 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-20 02:24:49.903158) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:49.903553) ========= script_repo_revision: 0750ccffec1e67c071071fe053acde164b53aa1d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:49.904046) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:49.904362) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-20 02:24:49.914065) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-20 02:24:49.914390) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:24:50 INFO - MultiFileLogger online at 20160920 02:24:50 in /builds/slave/test 02:24:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 02:24:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:24:50 INFO - {'append_to_log': False, 02:24:50 INFO - 'base_work_dir': '/builds/slave/test', 02:24:50 INFO - 'blob_upload_branch': 'Mozilla-Beta', 02:24:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:24:50 INFO - 'branch': 'Mozilla-Beta', 02:24:50 INFO - 'buildbot_json_path': 'buildprops.json', 02:24:50 INFO - 'config_files': ('talos/linux_config.py',), 02:24:50 INFO - 'default_actions': ('clobber', 02:24:50 INFO - 'read-buildbot-config', 02:24:50 INFO - 'download-and-extract', 02:24:50 INFO - 'populate-webroot', 02:24:50 INFO - 'create-virtualenv', 02:24:50 INFO - 'install', 02:24:50 INFO - 'run-tests'), 02:24:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:24:50 INFO - 'download_minidump_stackwalk': True, 02:24:50 INFO - 'download_symbols': 'ondemand', 02:24:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:24:50 INFO - 'tooltool.py': '/tools/tooltool.py', 02:24:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:24:50 INFO - '/tools/misc-python/virtualenv.py')}, 02:24:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:24:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:24:50 INFO - 'installer_path': 'installer.exe', 02:24:50 INFO - 'log_level': 'info', 02:24:50 INFO - 'log_name': 'talos', 02:24:50 INFO - 'log_to_console': True, 02:24:50 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:24:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:24:50 INFO - 'opt_config_files': (), 02:24:50 INFO - 'pip_index': False, 02:24:50 INFO - 'sps_profile': False, 02:24:50 INFO - 'sps_profile_interval': 0, 02:24:50 INFO - 'suite': 'dromaeojs', 02:24:50 INFO - 'system_bits': '32', 02:24:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:24:50 INFO - 'title': 'talos-linux64-ix-054', 02:24:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:24:50 INFO - 'use_talos_json': True, 02:24:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:24:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:24:50 INFO - 'work_dir': 'build'} 02:24:50 INFO - [mozharness: 2016-09-20 09:24:50.197597Z] Running clobber step. 02:24:50 INFO - Running pre-action listener: _resource_record_pre_action 02:24:50 INFO - Running main action method: clobber 02:24:50 INFO - rmtree: /builds/slave/test/build 02:24:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:24:50 INFO - Running post-action listener: _resource_record_post_action 02:24:50 INFO - [mozharness: 2016-09-20 09:24:50.657689Z] Finished clobber step (success) 02:24:50 INFO - [mozharness: 2016-09-20 09:24:50.657790Z] Running read-buildbot-config step. 02:24:50 INFO - Running pre-action listener: _resource_record_pre_action 02:24:50 INFO - Running main action method: read_buildbot_config 02:24:50 INFO - Using buildbot properties: 02:24:50 INFO - { 02:24:50 INFO - "project": "", 02:24:50 INFO - "product": "firefox", 02:24:50 INFO - "script_repo_revision": "production", 02:24:50 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 02:24:50 INFO - "repository": "", 02:24:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 02:24:50 INFO - "buildid": "20160920003916", 02:24:50 INFO - "pgo_build": "True", 02:24:50 INFO - "basedir": "/builds/slave/test", 02:24:50 INFO - "buildnumber": 390, 02:24:50 INFO - "slavename": "talos-linux64-ix-054", 02:24:50 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 02:24:50 INFO - "platform": "ubuntu64_hw", 02:24:50 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 02:24:50 INFO - "branch": "mozilla-beta", 02:24:50 INFO - "repo_path": "releases/mozilla-beta", 02:24:50 INFO - "revision": "0750ccffec1e67c071071fe053acde164b53aa1d", 02:24:50 INFO - "stage_platform": "linux64-pgo", 02:24:50 INFO - "builduid": "172665a53b3746cd894d45ae120de9f0", 02:24:50 INFO - "slavebuilddir": "test" 02:24:50 INFO - } 02:24:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 02:24:50 INFO - Running post-action listener: _resource_record_post_action 02:24:50 INFO - [mozharness: 2016-09-20 09:24:50.660200Z] Finished read-buildbot-config step (success) 02:24:50 INFO - [mozharness: 2016-09-20 09:24:50.660288Z] Running download-and-extract step. 02:24:50 INFO - Running pre-action listener: _resource_record_pre_action 02:24:50 INFO - Running main action method: download_and_extract 02:24:50 INFO - mkdir: /builds/slave/test/build/tests 02:24: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')]} 02:24:50 INFO - https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 02:24:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 02:24:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 02:24:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 02:24:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:24:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 02:24:50 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 02:24:50 INFO - retry: Failed, sleeping 30 seconds before retrying 02:25:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 02:25:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 02:25:20 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 02:25:20 INFO - retry: Failed, sleeping 60 seconds before retrying 02:26:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 02:26:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 02:26:20 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 02:26:20 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 02:26:20 INFO - Caught exception: HTTP Error 404: Not Found 02:26:20 INFO - Caught exception: HTTP Error 404: Not Found 02:26:20 INFO - Caught exception: HTTP Error 404: Not Found 02:26:20 INFO - trying https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 02:26:20 INFO - Downloading https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 02:26:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:26:21 INFO - Downloaded 1327 bytes. 02:26:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 02:26:21 INFO - Using the following test package requirements: 02:26:21 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 02:26:21 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 02:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 02:26:21 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 02:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 02:26:21 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 02:26:21 INFO - u'jsshell-linux-x86_64.zip'], 02:26:21 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 02:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 02:26:21 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 02:26:21 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 02:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 02:26:21 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 02:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 02:26:21 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 02:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 02:26:21 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 02:26:21 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 02:26:21 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:26:21 INFO - https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:26:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:26:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:26:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:26:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:26:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:26:21 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 02:26:21 INFO - retry: Failed, sleeping 30 seconds before retrying 02:26:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 02:26:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:26:51 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 02:26:51 INFO - retry: Failed, sleeping 60 seconds before retrying 02:27:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 02:27:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:27:51 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 02:27:51 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 02:27:51 INFO - Caught exception: HTTP Error 404: Not Found 02:27:51 INFO - Caught exception: HTTP Error 404: Not Found 02:27:51 INFO - Caught exception: HTTP Error 404: Not Found 02:27:51 INFO - trying https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:27:51 INFO - Downloading https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:27:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:27:52 INFO - Downloaded 21771536 bytes. 02:27:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:27:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:27:53 INFO - Return code: 0 02:27:53 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:27:53 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')]} 02:27:53 INFO - https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:27:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:27:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:27:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:27:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:27:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:27:53 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 02:27:53 INFO - retry: Failed, sleeping 30 seconds before retrying 02:28:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 02:28:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:28:23 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 02:28:23 INFO - retry: Failed, sleeping 60 seconds before retrying 02:29:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 02:29:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:29:23 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 02:29:23 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 02:29:23 INFO - Caught exception: HTTP Error 404: Not Found 02:29:23 INFO - Caught exception: HTTP Error 404: Not Found 02:29:23 INFO - Caught exception: HTTP Error 404: Not Found 02:29:23 INFO - trying https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:29:23 INFO - Downloading https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 02:29:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:29:24 INFO - Downloaded 21771536 bytes. 02:29:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:29:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:29:25 INFO - Return code: 0 02:29:25 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')]} 02:29:25 INFO - https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 02:29:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 02:29:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 02:29:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 02:29:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:29:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 02:29:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 02:29:25 INFO - retry: Failed, sleeping 30 seconds before retrying 02:29:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 02:29:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 02:29:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 02:29:55 INFO - retry: Failed, sleeping 60 seconds before retrying 02:30:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 02:30:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 02:30:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 02:30:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 02:30:55 INFO - Caught exception: HTTP Error 404: Not Found 02:30:55 INFO - Caught exception: HTTP Error 404: Not Found 02:30:55 INFO - Caught exception: HTTP Error 404: Not Found 02:30:55 INFO - trying https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 02:30:55 INFO - Downloading https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 02:30:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:30:56 INFO - Downloaded 14413740 bytes. 02:30:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:30:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 02:30:56 INFO - Return code: 0 02:30: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')]} 02:30:56 INFO - https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 02:30:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 02:30:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 02:30:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 02:30:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:30:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 02:30:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 02:30:56 INFO - retry: Failed, sleeping 30 seconds before retrying 02:31:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 02:31:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 02:31:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 02:31:26 INFO - retry: Failed, sleeping 60 seconds before retrying 02:32:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 02:32:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 02:32:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 02:32:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 02:32:26 INFO - Caught exception: HTTP Error 404: Not Found 02:32:26 INFO - Caught exception: HTTP Error 404: Not Found 02:32:26 INFO - Caught exception: HTTP Error 404: Not Found 02:32:26 INFO - trying https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 02:32:26 INFO - Downloading https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 02:32:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:32:28 INFO - Downloaded 56778048 bytes. 02:32:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 02:32:28 INFO - mkdir: /builds/slave/test/properties 02:32:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:32:28 INFO - Writing to file /builds/slave/test/properties/build_url 02:32:28 INFO - Contents: 02:32:28 INFO - build_url:https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 02:32:28 INFO - Running post-action listener: _resource_record_post_action 02:32:28 INFO - Running post-action listener: set_extra_try_arguments 02:32:28 INFO - [mozharness: 2016-09-20 09:32:28.732499Z] Finished download-and-extract step (success) 02:32:28 INFO - [mozharness: 2016-09-20 09:32:28.732730Z] Running populate-webroot step. 02:32:28 INFO - Running pre-action listener: _resource_record_pre_action 02:32:28 INFO - Running main action method: populate_webroot 02:32:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:32:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:32:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:32:28 INFO - '--tppagecycles', 02:32:28 INFO - '7'], 02:32:28 INFO - 'tests': ['tsvgm']}}, 02:32:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:32:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:32:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:32:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:32:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:32:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:32:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['damp', 'tps']}, 02:32:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'tests': ['damp', 'tps']}, 02:32:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['dromaeo_dom']}, 02:32:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:32:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['basic_compositor_video']}, 02:32:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:32:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'other-e10s': {'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['tsvgx', 02:32:28 INFO - 'tsvgr_opacity', 02:32:28 INFO - 'tart', 02:32:28 INFO - 'tscrollx', 02:32:28 INFO - 'cart']}, 02:32:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:32:28 INFO - 'tsvgr_opacity', 02:32:28 INFO - 'tart', 02:32:28 INFO - 'tscrollx', 02:32:28 INFO - 'cart']}, 02:32:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['tp5o']}, 02:32:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'tests': ['tp5o']}, 02:32:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'talos_options': ['--disable-e10s', 02:32:28 INFO - '--xperf_path', 02:32:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:32:28 INFO - 'tests': ['tp5n']}, 02:32:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'talos_options': ['--xperf_path', 02:32:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:32:28 INFO - 'tests': ['tp5n']}}, 02:32:28 INFO - 'talos.zip': {'path': '', 02:32:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:32:28 INFO - Running post-action listener: _resource_record_post_action 02:32:28 INFO - [mozharness: 2016-09-20 09:32:28.749017Z] Finished populate-webroot step (success) 02:32:28 INFO - [mozharness: 2016-09-20 09:32:28.749106Z] Running create-virtualenv step. 02:32:28 INFO - Running pre-action listener: _resource_record_pre_action 02:32:28 INFO - Running main action method: create_virtualenv 02:32:28 INFO - Creating virtualenv /builds/slave/test/build/venv 02:32:28 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 02:32:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:32:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:32:28 INFO - Using real prefix '/usr' 02:32:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:32:29 INFO - Installing distribute.............................................................................................................................................................................................done. 02:32:31 INFO - Installing pip.................done. 02:32:31 INFO - Return code: 0 02:32:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:32:31 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')]} 02:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:31 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')]} 02:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:31 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 0x2677030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2938180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29363b0>, '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 0x29372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2937740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', '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 02:32:31 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 02:32:31 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 02:32:31 INFO - Using env: {'DISPLAY': ':0', 02:32:31 INFO - 'HOME': '/home/cltbld', 02:32:31 INFO - 'LANG': 'en_US.UTF-8', 02:32:31 INFO - 'LANGUAGE': 'en_US:en', 02:32:31 INFO - 'LOGNAME': 'cltbld', 02:32:31 INFO - 'MAIL': '/var/mail/cltbld', 02:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:31 INFO - 'MOZ_NO_REMOTE': '1', 02:32:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:31 INFO - 'NO_EM_RESTART': '1', 02:32:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:31 INFO - 'PWD': '/builds/slave/test', 02:32:31 INFO - 'SHELL': '/bin/bash', 02:32:31 INFO - 'SHLVL': '1', 02:32:31 INFO - 'TERM': 'linux', 02:32:31 INFO - 'TMOUT': '86400', 02:32:31 INFO - 'USER': 'cltbld', 02:32:31 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', 02:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:31 INFO - '_': '/tools/buildbot/bin/python'} 02:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:31 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 02:32:31 INFO - Cleaning up... 02:32:31 INFO - Return code: 0 02:32:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:32:31 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')]} 02:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:31 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')]} 02:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:31 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 0x2677030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2938180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29363b0>, '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 0x29372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2937740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', '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 02:32:31 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 02:32:31 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 02:32:31 INFO - Using env: {'DISPLAY': ':0', 02:32:31 INFO - 'HOME': '/home/cltbld', 02:32:31 INFO - 'LANG': 'en_US.UTF-8', 02:32:31 INFO - 'LANGUAGE': 'en_US:en', 02:32:31 INFO - 'LOGNAME': 'cltbld', 02:32:31 INFO - 'MAIL': '/var/mail/cltbld', 02:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:31 INFO - 'MOZ_NO_REMOTE': '1', 02:32:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:31 INFO - 'NO_EM_RESTART': '1', 02:32:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:31 INFO - 'PWD': '/builds/slave/test', 02:32:31 INFO - 'SHELL': '/bin/bash', 02:32:31 INFO - 'SHLVL': '1', 02:32:31 INFO - 'TERM': 'linux', 02:32:31 INFO - 'TMOUT': '86400', 02:32:31 INFO - 'USER': 'cltbld', 02:32:31 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', 02:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:31 INFO - '_': '/tools/buildbot/bin/python'} 02:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:31 INFO - Downloading/unpacking psutil>=3.1.1 02:32:31 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 02:32:31 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 02:32:31 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 02:32:31 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 02:32:31 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 02:32:31 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 02:32:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:32:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:32:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:32:33 INFO - Installing collected packages: psutil 02:32:33 INFO - Running setup.py install for psutil 02:32:33 INFO - building 'psutil._psutil_linux' extension 02:32:33 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 02:32:33 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 02:32:33 INFO - building 'psutil._psutil_posix' extension 02:32:33 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 02:32:34 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 02:32:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:32:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:32:34 INFO - Successfully installed psutil 02:32:34 INFO - Cleaning up... 02:32:34 INFO - Return code: 0 02:32:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:32: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')]} 02:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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')]} 02:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:34 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 0x2677030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2938180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29363b0>, '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 0x29372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2937740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', '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 02:32:34 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 02:32:34 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 02:32:34 INFO - Using env: {'DISPLAY': ':0', 02:32:34 INFO - 'HOME': '/home/cltbld', 02:32:34 INFO - 'LANG': 'en_US.UTF-8', 02:32:34 INFO - 'LANGUAGE': 'en_US:en', 02:32:34 INFO - 'LOGNAME': 'cltbld', 02:32:34 INFO - 'MAIL': '/var/mail/cltbld', 02:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:34 INFO - 'MOZ_NO_REMOTE': '1', 02:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:34 INFO - 'NO_EM_RESTART': '1', 02:32:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:34 INFO - 'PWD': '/builds/slave/test', 02:32:34 INFO - 'SHELL': '/bin/bash', 02:32:34 INFO - 'SHLVL': '1', 02:32:34 INFO - 'TERM': 'linux', 02:32:34 INFO - 'TMOUT': '86400', 02:32:34 INFO - 'USER': 'cltbld', 02:32:34 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', 02:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:34 INFO - '_': '/tools/buildbot/bin/python'} 02:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:32:34 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 02:32:34 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 02:32:34 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 02:32:34 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 02:32:34 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 02:32:34 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 02:32:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:32:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:32:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:32:36 INFO - Installing collected packages: mozsystemmonitor 02:32:36 INFO - Running setup.py install for mozsystemmonitor 02:32:36 INFO - Successfully installed mozsystemmonitor 02:32:36 INFO - Cleaning up... 02:32:36 INFO - Return code: 0 02:32:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:32:36 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')]} 02:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:36 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')]} 02:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:36 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 0x2677030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2938180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29363b0>, '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 0x29372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2937740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', '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 02:32:36 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 02:32:36 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 02:32:36 INFO - Using env: {'DISPLAY': ':0', 02:32:36 INFO - 'HOME': '/home/cltbld', 02:32:36 INFO - 'LANG': 'en_US.UTF-8', 02:32:36 INFO - 'LANGUAGE': 'en_US:en', 02:32:36 INFO - 'LOGNAME': 'cltbld', 02:32:36 INFO - 'MAIL': '/var/mail/cltbld', 02:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:36 INFO - 'MOZ_NO_REMOTE': '1', 02:32:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:36 INFO - 'NO_EM_RESTART': '1', 02:32:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:36 INFO - 'PWD': '/builds/slave/test', 02:32:36 INFO - 'SHELL': '/bin/bash', 02:32:36 INFO - 'SHLVL': '1', 02:32:36 INFO - 'TERM': 'linux', 02:32:36 INFO - 'TMOUT': '86400', 02:32:36 INFO - 'USER': 'cltbld', 02:32:36 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', 02:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:36 INFO - '_': '/tools/buildbot/bin/python'} 02:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:36 INFO - Downloading/unpacking jsonschema==2.5.1 02:32:36 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 02:32:36 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 02:32:36 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 02:32:36 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 02:32:36 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 02:32:36 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 02:32:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:32:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:38 INFO - Downloading functools32-3.2.3-2.tar.gz 02:32:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:32:38 INFO - warning: no files found matching '*.txt' 02:32:38 INFO - no previously-included directories found matching 'build' 02:32:38 INFO - no previously-included directories found matching 'dist' 02:32:38 INFO - no previously-included directories found matching '.git*' 02:32:38 INFO - Installing collected packages: jsonschema, functools32 02:32:39 INFO - Running setup.py install for functools32 02:32:39 INFO - warning: no files found matching '*.txt' 02:32:39 INFO - no previously-included directories found matching 'build' 02:32:39 INFO - no previously-included directories found matching 'dist' 02:32:39 INFO - no previously-included directories found matching '.git*' 02:32:39 INFO - Successfully installed jsonschema functools32 02:32:39 INFO - Cleaning up... 02:32:39 INFO - Return code: 0 02:32:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:39 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2677030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2938180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29363b0>, '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 0x29372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2937740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', '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 02:32:39 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:32:39 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 functools32==3.2.3-2 02:32:39 INFO - Using env: {'DISPLAY': ':0', 02:32:39 INFO - 'HOME': '/home/cltbld', 02:32:39 INFO - 'LANG': 'en_US.UTF-8', 02:32:39 INFO - 'LANGUAGE': 'en_US:en', 02:32:39 INFO - 'LOGNAME': 'cltbld', 02:32:39 INFO - 'MAIL': '/var/mail/cltbld', 02:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:39 INFO - 'MOZ_NO_REMOTE': '1', 02:32:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:39 INFO - 'NO_EM_RESTART': '1', 02:32:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:39 INFO - 'PWD': '/builds/slave/test', 02:32:39 INFO - 'SHELL': '/bin/bash', 02:32:39 INFO - 'SHLVL': '1', 02:32:39 INFO - 'TERM': 'linux', 02:32:39 INFO - 'TMOUT': '86400', 02:32:39 INFO - 'USER': 'cltbld', 02:32:39 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', 02:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:39 INFO - '_': '/tools/buildbot/bin/python'} 02:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:32:39 INFO - Cleaning up... 02:32:39 INFO - Return code: 0 02:32:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:39 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 0x2677030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2938180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29363b0>, '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 0x29372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2937740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', '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 02:32:39 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 02:32:39 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 02:32:39 INFO - Using env: {'DISPLAY': ':0', 02:32:39 INFO - 'HOME': '/home/cltbld', 02:32:39 INFO - 'LANG': 'en_US.UTF-8', 02:32:39 INFO - 'LANGUAGE': 'en_US:en', 02:32:39 INFO - 'LOGNAME': 'cltbld', 02:32:39 INFO - 'MAIL': '/var/mail/cltbld', 02:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:39 INFO - 'MOZ_NO_REMOTE': '1', 02:32:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:39 INFO - 'NO_EM_RESTART': '1', 02:32:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:39 INFO - 'PWD': '/builds/slave/test', 02:32:39 INFO - 'SHELL': '/bin/bash', 02:32:39 INFO - 'SHLVL': '1', 02:32:39 INFO - 'TERM': 'linux', 02:32:39 INFO - 'TMOUT': '86400', 02:32:39 INFO - 'USER': 'cltbld', 02:32:39 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', 02:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:39 INFO - '_': '/tools/buildbot/bin/python'} 02:32:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:39 INFO - Downloading/unpacking blobuploader==1.2.4 02:32:39 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 02:32:39 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 02:32:39 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 02:32:39 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 02:32:39 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 02:32:39 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 02:32:41 INFO - Downloading blobuploader-1.2.4.tar.gz 02:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:32:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:32:41 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 02:32:41 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 02:32:41 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 02:32:41 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 02:32:41 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 02:32:41 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 02:32:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:32:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:32:41 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 02:32:41 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 02:32:41 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 02:32:41 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 02:32:41 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 02:32:41 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 02:32:42 INFO - Downloading docopt-0.6.1.tar.gz 02:32:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:32:42 INFO - Installing collected packages: blobuploader, requests, docopt 02:32:42 INFO - Running setup.py install for blobuploader 02:32:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:32:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:32:42 INFO - Running setup.py install for requests 02:32:42 INFO - Running setup.py install for docopt 02:32:42 INFO - Successfully installed blobuploader requests docopt 02:32:42 INFO - Cleaning up... 02:32:42 INFO - Return code: 0 02:32:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:42 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 0x2677030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2938180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29363b0>, '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 0x29372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2937740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', '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 02:32:42 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 02:32:42 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 02:32:42 INFO - Using env: {'DISPLAY': ':0', 02:32:42 INFO - 'HOME': '/home/cltbld', 02:32:42 INFO - 'LANG': 'en_US.UTF-8', 02:32:42 INFO - 'LANGUAGE': 'en_US:en', 02:32:42 INFO - 'LOGNAME': 'cltbld', 02:32:42 INFO - 'MAIL': '/var/mail/cltbld', 02:32:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:42 INFO - 'MOZ_NO_REMOTE': '1', 02:32:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:42 INFO - 'NO_EM_RESTART': '1', 02:32:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:42 INFO - 'PWD': '/builds/slave/test', 02:32:42 INFO - 'SHELL': '/bin/bash', 02:32:42 INFO - 'SHLVL': '1', 02:32:42 INFO - 'TERM': 'linux', 02:32:42 INFO - 'TMOUT': '86400', 02:32:42 INFO - 'USER': 'cltbld', 02:32:42 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', 02:32:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:42 INFO - '_': '/tools/buildbot/bin/python'} 02:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:32:43 INFO - Running setup.py (path:/tmp/pip-_HhUiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:32:43 INFO - Running setup.py (path:/tmp/pip-C69EEl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:32:43 INFO - Running setup.py (path:/tmp/pip-3gZ2xn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:32:43 INFO - Running setup.py (path:/tmp/pip-bG5Qfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:32:43 INFO - Running setup.py (path:/tmp/pip-jstJN5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:32:43 INFO - Running setup.py (path:/tmp/pip-be0ehz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:32:43 INFO - Running setup.py (path:/tmp/pip-yHFe5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:32:43 INFO - Running setup.py (path:/tmp/pip-wgc6BJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:32:43 INFO - Running setup.py (path:/tmp/pip-YeV14v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:32:43 INFO - Running setup.py (path:/tmp/pip-G4WFEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:32:43 INFO - Running setup.py (path:/tmp/pip-Nji4Jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:43 INFO - Running setup.py (path:/tmp/pip-BNbuR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:43 INFO - Running setup.py (path:/tmp/pip-RvKUo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:44 INFO - Running setup.py (path:/tmp/pip-KCkMT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:44 INFO - Running setup.py (path:/tmp/pip-LL2OxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:44 INFO - Running setup.py (path:/tmp/pip-XBS5zx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:44 INFO - Running setup.py (path:/tmp/pip-VP9KgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:32:44 INFO - Running setup.py install for manifestparser 02:32:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:32:44 INFO - Running setup.py install for mozcrash 02:32:44 INFO - Running setup.py install for mozdebug 02:32:44 INFO - Running setup.py install for mozdevice 02:32:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:32:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:32:44 INFO - Running setup.py install for mozfile 02:32:44 INFO - Running setup.py install for mozhttpd 02:32:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:32:44 INFO - Running setup.py install for mozinfo 02:32:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:32:44 INFO - Running setup.py install for mozInstall 02:32:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:32:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:32:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:32:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:32:45 INFO - Running setup.py install for mozleak 02:32:45 INFO - Running setup.py install for mozlog 02:32:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:32:45 INFO - Running setup.py install for moznetwork 02:32:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:32:45 INFO - Running setup.py install for mozprocess 02:32:45 INFO - Running setup.py install for mozprofile 02:32:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:32:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:32:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:32:45 INFO - Running setup.py install for mozrunner 02:32:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:32:45 INFO - Running setup.py install for mozscreenshot 02:32:45 INFO - Running setup.py install for moztest 02:32:45 INFO - Running setup.py install for mozversion 02:32:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:32:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:32:46 INFO - Cleaning up... 02:32:46 INFO - Return code: 0 02:32:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:32:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:46 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 0x2677030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2938180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29363b0>, '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 0x29372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2937740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', '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 02:32:46 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 02:32:46 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 02:32:46 INFO - Using env: {'DISPLAY': ':0', 02:32:46 INFO - 'HOME': '/home/cltbld', 02:32:46 INFO - 'LANG': 'en_US.UTF-8', 02:32:46 INFO - 'LANGUAGE': 'en_US:en', 02:32:46 INFO - 'LOGNAME': 'cltbld', 02:32:46 INFO - 'MAIL': '/var/mail/cltbld', 02:32:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:46 INFO - 'MOZ_NO_REMOTE': '1', 02:32:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:46 INFO - 'NO_EM_RESTART': '1', 02:32:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:46 INFO - 'PWD': '/builds/slave/test', 02:32:46 INFO - 'SHELL': '/bin/bash', 02:32:46 INFO - 'SHLVL': '1', 02:32:46 INFO - 'TERM': 'linux', 02:32:46 INFO - 'TMOUT': '86400', 02:32:46 INFO - 'USER': 'cltbld', 02:32:46 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', 02:32:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:46 INFO - '_': '/tools/buildbot/bin/python'} 02:32:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:32:46 INFO - Running setup.py (path:/tmp/pip-q41Mjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:32:46 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)) 02:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:32:46 INFO - Running setup.py (path:/tmp/pip-Tq_eJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:32:46 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)) 02:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:32:46 INFO - Running setup.py (path:/tmp/pip-IEmrhR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:32:46 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)) 02:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:32:46 INFO - Running setup.py (path:/tmp/pip-JQjG8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:32:46 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)) 02:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:32:46 INFO - Running setup.py (path:/tmp/pip-kV8dbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:32:46 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)) 02:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:32:46 INFO - Running setup.py (path:/tmp/pip-WM5gut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:32:46 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)) 02:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:32:46 INFO - Running setup.py (path:/tmp/pip-ruhBMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:32:46 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)) 02:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:32:46 INFO - Running setup.py (path:/tmp/pip-OmSlVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:32:46 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)) 02:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:32:46 INFO - Running setup.py (path:/tmp/pip-YZV2rr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:32:46 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)) 02:32:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:32:46 INFO - Running setup.py (path:/tmp/pip-2vEAKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:32:47 INFO - Running setup.py (path:/tmp/pip-llezwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:47 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)) 02:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:47 INFO - Running setup.py (path:/tmp/pip-U1vZjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:47 INFO - Running setup.py (path:/tmp/pip-7WJjot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:47 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)) 02:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:47 INFO - Running setup.py (path:/tmp/pip-w2a1AK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:47 INFO - Running setup.py (path:/tmp/pip-WNKep6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:47 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)) 02:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:47 INFO - Running setup.py (path:/tmp/pip-oMKE4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:47 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)) 02:32:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:47 INFO - Running setup.py (path:/tmp/pip-bGp7VO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:47 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)) 02:32:47 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)) 02:32:47 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)) 02:32:47 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)) 02:32:47 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)) 02:32:47 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)) 02:32:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:32:47 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 02:32:47 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 02:32:47 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 02:32:47 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 02:32:47 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 02:32:47 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 02:32:49 INFO - Downloading blessings-1.6.tar.gz 02:32:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:32:49 INFO - Installing collected packages: blessings 02:32:49 INFO - Running setup.py install for blessings 02:32:49 INFO - Successfully installed blessings 02:32:49 INFO - Cleaning up... 02:32:49 INFO - Return code: 0 02:32:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:32:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:49 INFO - Reading from file tmpfile_stdout 02:32:49 INFO - Current package versions: 02:32:49 INFO - argparse == 1.2.1 02:32:49 INFO - blessings == 1.6 02:32:49 INFO - blobuploader == 1.2.4 02:32:49 INFO - docopt == 0.6.1 02:32:49 INFO - functools32 == 3.2.3-2 02:32:49 INFO - jsonschema == 2.5.1 02:32:49 INFO - manifestparser == 1.1 02:32:49 INFO - mozInstall == 1.12 02:32:49 INFO - mozcrash == 0.17 02:32:49 INFO - mozdebug == 0.1 02:32:49 INFO - mozdevice == 0.48 02:32:49 INFO - mozfile == 1.2 02:32:49 INFO - mozhttpd == 0.7 02:32:49 INFO - mozinfo == 0.9 02:32:49 INFO - mozleak == 0.1 02:32:49 INFO - mozlog == 3.3 02:32:49 INFO - moznetwork == 0.27 02:32:49 INFO - mozprocess == 0.23 02:32:49 INFO - mozprofile == 0.28 02:32:49 INFO - mozrunner == 6.12 02:32:49 INFO - mozscreenshot == 0.1 02:32:49 INFO - mozsystemmonitor == 0.3 02:32:49 INFO - moztest == 0.7 02:32:49 INFO - mozversion == 1.4 02:32:49 INFO - psutil == 3.1.1 02:32:49 INFO - requests == 1.2.3 02:32:49 INFO - wsgiref == 0.1.2 02:32:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:49 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')]} 02:32:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:49 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')]} 02:32:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:49 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 0x2677030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2938180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29363b0>, '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 0x29372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2937740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', '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 02:32:49 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 02:32:49 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 02:32:49 INFO - Using env: {'DISPLAY': ':0', 02:32:49 INFO - 'HOME': '/home/cltbld', 02:32:49 INFO - 'LANG': 'en_US.UTF-8', 02:32:49 INFO - 'LANGUAGE': 'en_US:en', 02:32:49 INFO - 'LOGNAME': 'cltbld', 02:32:49 INFO - 'MAIL': '/var/mail/cltbld', 02:32:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:49 INFO - 'MOZ_NO_REMOTE': '1', 02:32:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:49 INFO - 'NO_EM_RESTART': '1', 02:32:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:49 INFO - 'PWD': '/builds/slave/test', 02:32:49 INFO - 'SHELL': '/bin/bash', 02:32:49 INFO - 'SHLVL': '1', 02:32:49 INFO - 'TERM': 'linux', 02:32:49 INFO - 'TMOUT': '86400', 02:32:49 INFO - 'USER': 'cltbld', 02:32:49 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', 02:32:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:49 INFO - '_': '/tools/buildbot/bin/python'} 02:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:50 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)) 02:32:50 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)) 02:32:50 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)) 02:32:50 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)) 02:32:50 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)) 02:32:50 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)) 02:32:50 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)) 02:32:50 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)) 02:32:50 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)) 02:32:50 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)) 02:32:50 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)) 02:32:50 INFO - Cleaning up... 02:32:50 INFO - Return code: 0 02:32:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:32: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')]} 02:32:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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')]} 02:32:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:50 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 0x2677030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27da8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2938180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29363b0>, '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 0x29372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2937740>, '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': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', '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 02:32:50 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 02:32:50 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 02:32:50 INFO - Using env: {'DISPLAY': ':0', 02:32:50 INFO - 'HOME': '/home/cltbld', 02:32:50 INFO - 'LANG': 'en_US.UTF-8', 02:32:50 INFO - 'LANGUAGE': 'en_US:en', 02:32:50 INFO - 'LOGNAME': 'cltbld', 02:32:50 INFO - 'MAIL': '/var/mail/cltbld', 02:32:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:50 INFO - 'MOZ_NO_REMOTE': '1', 02:32:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:32:50 INFO - 'NO_EM_RESTART': '1', 02:32:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:32:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:50 INFO - 'PWD': '/builds/slave/test', 02:32:50 INFO - 'SHELL': '/bin/bash', 02:32:50 INFO - 'SHLVL': '1', 02:32:50 INFO - 'TERM': 'linux', 02:32:50 INFO - 'TMOUT': '86400', 02:32:50 INFO - 'USER': 'cltbld', 02:32:50 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', 02:32:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:50 INFO - '_': '/tools/buildbot/bin/python'} 02:32:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:32:50 INFO - Cleaning up... 02:32:50 INFO - Return code: 0 02:32:50 INFO - Running post-action listener: _resource_record_post_action 02:32:50 INFO - Running post-action listener: _start_resource_monitoring 02:32:50 INFO - Starting resource monitoring. 02:32:50 INFO - [mozharness: 2016-09-20 09:32:50.364201Z] Finished create-virtualenv step (success) 02:32:50 INFO - [mozharness: 2016-09-20 09:32:50.364624Z] Running install step. 02:32:50 INFO - Running pre-action listener: _resource_record_pre_action 02:32:50 INFO - Running main action method: install 02:32:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:50 INFO - Reading from file tmpfile_stdout 02:32:50 INFO - Detecting whether we're running mozinstall >=1.0... 02:32:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:32:50 INFO - Reading from file tmpfile_stdout 02:32:50 INFO - Output received: 02:32:50 INFO - Usage: mozinstall [options] installer 02:32:50 INFO - Options: 02:32:50 INFO - -h, --help show this help message and exit 02:32:50 INFO - -d DEST, --destination=DEST 02:32:50 INFO - Directory to install application into. [default: 02:32:50 INFO - "/builds/slave/test"] 02:32:50 INFO - --app=APP Application being installed. [default: firefox] 02:32:50 INFO - mkdir: /builds/slave/test/build/application 02:32:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:33:04 INFO - Reading from file tmpfile_stdout 02:33:04 INFO - Output received: 02:33:04 INFO - /builds/slave/test/build/application/firefox/firefox 02:33:04 INFO - Running post-action listener: _resource_record_post_action 02:33:04 INFO - [mozharness: 2016-09-20 09:33:04.867535Z] Finished install step (success) 02:33:04 INFO - [mozharness: 2016-09-20 09:33:04.867812Z] Running run-tests step. 02:33:04 INFO - Running pre-action listener: _resource_record_pre_action 02:33:04 INFO - Running main action method: run_tests 02:33:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:33:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:33:04 INFO - Python 2.7.3 02:33:04 INFO - Return code: 0 02:33:04 INFO - grabbing minidump binary from tooltool 02:33: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')]} 02:33:04 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29363b0>, '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 0x29372b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2937740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:33:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:33:04 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 02:33:04 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:33:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:33:04 INFO - Return code: 0 02:33:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:33:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:33:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:33:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:33:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:33:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:33:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-054 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:33:04 INFO - Using env: {'DISPLAY': ':0', 02:33:04 INFO - 'HOME': '/home/cltbld', 02:33:04 INFO - 'LANG': 'en_US.UTF-8', 02:33:04 INFO - 'LANGUAGE': 'en_US:en', 02:33:04 INFO - 'LOGNAME': 'cltbld', 02:33:04 INFO - 'MAIL': '/var/mail/cltbld', 02:33:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:33:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:33:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:04 INFO - 'MOZ_NO_REMOTE': '1', 02:33:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:33:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:33:04 INFO - 'NO_EM_RESTART': '1', 02:33:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:33:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:04 INFO - 'PWD': '/builds/slave/test', 02:33:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:33:04 INFO - 'SHELL': '/bin/bash', 02:33:04 INFO - 'SHLVL': '1', 02:33:04 INFO - 'TERM': 'linux', 02:33:04 INFO - 'TMOUT': '86400', 02:33:04 INFO - 'USER': 'cltbld', 02:33:04 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251', 02:33:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:04 INFO - '_': '/tools/buildbot/bin/python'} 02:33:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:33:05 INFO - mozversion application_buildid: 20160920003916 02:33:05 INFO - mozversion application_changeset: 0750ccffec1e67c071071fe053acde164b53aa1d 02:33:05 INFO - mozversion application_display_name: Firefox 02:33:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:33:05 INFO - mozversion application_name: Firefox 02:33:05 INFO - mozversion application_remotingname: firefox 02:33:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:33:05 INFO - mozversion application_vendor: Mozilla 02:33:05 INFO - mozversion application_version: 50.0 02:33:05 INFO - mozversion platform_buildid: 20160920003916 02:33:05 INFO - mozversion platform_changeset: 0750ccffec1e67c071071fe053acde164b53aa1d 02:33:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:33:05 INFO - mozversion platform_version: 50.0 02:33:05 INFO - using testdate: 1474363985 02:33:05 INFO - actual date: 1474363985 02:33:05 INFO - starting webserver on 'localhost:51903' 02:33:05 INFO - SUITE-START | Running 2 tests 02:33:05 INFO - TEST-START | dromaeo_css 02:33:05 INFO - operating with platform_type : linux_ 02:33:05 INFO - Initialising browser for dromaeo_css test... 02:33:05 INFO - TEST-INFO | started process 1885 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXk0mTW/profile http://localhost:51903/getInfo.html) 02:33:09 INFO - PROCESS | 1885 | __metrics Screen width/height:1600/1200 02:33:09 INFO - PROCESS | 1885 | colorDepth:24 02:33:09 INFO - PROCESS | 1885 | Browser inner width/height: 1024/697 02:33:09 INFO - PROCESS | 1885 | __metrics 02:33:10 INFO - PROCESS | 1885 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:33:10 INFO - PROCESS | 1885 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:33:10 INFO - PROCESS | 1885 | console.error: PushService: 02:33:10 INFO - PROCESS | 1885 | stateChangeProcessEnqueue: Error transitioning state 02:33:10 INFO - PROCESS | 1885 | UnknownError 02:33:11 INFO - TEST-INFO | 1885: exit 0 02:33:11 INFO - Browser initialized. 02:33:11 INFO - Running cycle 1/1 for dromaeo_css test... 02:33:11 INFO - TEST-INFO | started process 1974 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXk0mTW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:33:22 INFO - PROCESS | 1974 | RSS: Main: 143503360 02:33:22 INFO - PROCESS | 1974 | 02:34:13 INFO - PROCESS | 1974 | 1474364053906 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] 02:36:23 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51903/tests/dromaeo/cssquery-ext.html) 02:36:24 INFO - PROCESS | 1974 | RSS: Main: 262672384 02:36:24 INFO - PROCESS | 1974 | 02:39:26 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-ext.html (next: http://localhost:51903/tests/dromaeo/cssquery-jquery.html) 02:39:27 INFO - PROCESS | 1974 | RSS: Main: 237989888 02:39:27 INFO - PROCESS | 1974 | 02:42:29 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51903/tests/dromaeo/cssquery-mootools.html) 02:42:29 INFO - PROCESS | 1974 | RSS: Main: 191864832 02:42:29 INFO - PROCESS | 1974 | 02:45:31 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51903/tests/dromaeo/cssquery-prototype.html) 02:45:32 INFO - PROCESS | 1974 | RSS: Main: 175869952 02:45:32 INFO - PROCESS | 1974 | 02:48:34 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51903/tests/dromaeo/cssquery-yui.html) 02:48:35 INFO - PROCESS | 1974 | RSS: Main: 176951296 02:48:35 INFO - PROCESS | 1974 | 02:51:37 INFO - PROCESS | 1974 | Cycle 1(1): loaded http://localhost:51903/tests/dromaeo/cssquery-yui.html (next: http://localhost:51903/tests/dromaeo/cssquery-dojo.html) 02:51:37 INFO - PROCESS | 1974 | RSS: Main: 257671168 02:51:37 INFO - PROCESS | 1974 | 02:51:37 INFO - PROCESS | 1974 | __start_tp_report 02:51:37 INFO - PROCESS | 1974 | _x_x_mozilla_page_load 02:51:37 INFO - PROCESS | 1974 | _x_x_mozilla_page_load_details 02:51:37 INFO - PROCESS | 1974 | |i|pagename|runs| 02:51:37 INFO - PROCESS | 1974 | |0;dojo.html;3265.4611989021814;3275.115718755935;3294.7364210863147;3318.817465039425;3330.866765329387;5548.500634942854;5670.858228544288;5671.2060311556315;5684.54523638109;5686.744096515657;5682.147677848325;5764.567657425689;5773.336066352371;5773.971130144343;5784.219130417392;5043.470435604264;5224.895502089956;5230.1108811502045;5288.286081379003;5309.973450132744;5107.029664363778;5202.973985130069;5221.556167725748;5238.607104467161;5247.763850626712;5084.949150508503;5142.07442660321;5156.252343410203;5157.5100365465405;5246;5247.580193584504;5247.658902171365;5257.0274499217385;5264.1051021326275;5295.0998330283555;5054.469280725544;5088.643794934356;5109.795608175668;5157.767900444489;5160.509751573594;5626.549876009909;5640.464155905182;5665.773369065232;5667.178259152441;5698.230738850285;6200.565960382775;6212.81361559154;6215.502759779207;6219.595726277777;6225.377462253784;5944.189887713079;6035.185249991239;6051.697415129226;6096.603720758137;6222.5644204905675;5739.110437882135;5740.799072032459;5742.655440673573;5755.539556835485;5768.74040668171;6241.550809875124;6267.247930248308;6269.373062693785;6270.310265870842;6278.183836101278;5809.099589563624;6017.1275165100815;6080.14877917092;6083.117947897618;6124.991279909863;6194.5354098442795;6202.41077097675;6206.751729930843;6207.81376558704;6209.192804935329;6138.846528836725;6172.475339596184;6186;6187.195664563578;6187.969060154671;5935.376785437553;6016.969915150396;6067.211262535842;6070.514358851281;6137.285055221507;4656.8137274508645;4691.812327506861;4694.319323698113;4715.080559290974;4723;4160.667146628323;4176.937345939813;4181.477315335583;4181.853635122756;4209.284421648327;5646.6894320811925;5806.68063256525;5830.271216097988;5849.239598852122;5853.5317174625925;6198.876022479615;6199.504039676815;6202.069689546604;6209.689515524296;6217.595856269328;5862.325832529228;5877;5881.941180588225;5883.588148829541;5911.349751527328;5183.5593974512585;5265.894682106336;5280.788768449219;5312.203169524602;5339.439358867341;6409.359064093554;6442.903356449563;6457.644829534421;6463.935360646427;6469.061986012002;6194.5044396449075;6262.874742505033;6263.906041409474;6264.373562643699;6272.090546870587;6181.041938499539;6238.064290356483;6239.469645080219;6293.213348331458;6481.5138864584405;5851.795187168429;5941.37615550361;5947.9702601486715;6041.909371359521;6052.122442245938;6110.51115910735;6111.327753946974;6114.541409394402;6114.847128821815;6115.388461153849;5911.674857882857;5930.673812940329;5954.642721436728;5965.135055417027;5979.3123790762875;5757.596968212185;5840.883182336244;5923.4076592340425;5982.551308651781;6095.35998720128;6009.45914867664;6013.639181649115;6024.186734790747;6032.788852390146;6032.909506357496;6003.87992240144;6012.909806352821;6053.09203619443;6067.453929146398;6082.5438092142385;5699.316082070178;5808.273965754281;5866.911996320144;5871.7651293947765;5956.13636022783 02:51:37 INFO - PROCESS | 1974 | |1;ext.html;587.6092398554961;640.9294977552469;653.8201994451524;653.865957478717;654.2312782480584;36043.45934810977;46608.533914660846;46853.7657311713;46889;46988.53011469884;47882.52117478824;48444;48534.75732621341;48563.02873942528;48575.27137092946;8476.23713865752;9381.967983521825;9530.666426675032;9537.427754334762;9539.713808585753;11051;11336.659900203007;11343.546258149665;11548.422578871065;11634.883651163465;11448.828267575993;11541.596044138458;11555.653330400059;11561.479733412016;11570.074394048497;11264.267822591506;11304.773904521864;11315.490802913888;11316.886831131747;11329.773404531945;11165.553377864875;11208.66374008781;11225.494852731646;11242.831357529562;11245.269057511317;45585.316220256726;45965;46041.53958460406;46074.76962615199;46094.30858537125;3950.051987522974;4093.9385909211387;4190.664746820247;4211.1998720243055;4433.334999750031;34511.30977380438;34674.6532534674;34802;35093;35555.28889422197;4790.952090479085;4828.203346447832;4828.49300823412;4935.111679897617;4948.925766113512;361103;361756;362866;363473;364084.9999999973;66473;66619;66950.66524667345;67087;67205.32794672088;40209;40707.796461017504;40877;42975.77681673887;69660.6516967422;18221.362252321105;18354.449366519028;18386.540336491697;18390.816091839177;18401.539961500803;13279.26964016969;13747.175169489863;13788.93105534466;13876.791848122286;13983.79024314636;20711;20880.791192087883;21216.575668486854;21221.78778212198;21258.893705531373;7370.520916140434;7543.396528277724;7631;7675.424343174284;7779.844403111906;1047.7793370723175;1063.7659714862718;1119.5353928119903;1128.6726849213635;1159.1306520109918;35343.82328088343;35807;35970;35975.28049439049;36115.638843611756;1137.7724455109012;1168.696139003865;1189.191349882071;1227.4967263421954;1247.9126461147632;17672.911635441742;18762.343317983807;18812.435626931216;18866.151023203714;18977;635482;642682;643459;644023;644533;42766.78616606959;42807;42953.57046429496;42991.785041074596;43085;1365.215001374194;1444.4366696988184;1478.8225412950515;1490.3889405343973;1495.2338174377533;33630.159246019044;35647;35810.64189358073;36111.81944090368;36144.638553614124;55174.724126379115;55951;56046.43953560575;56335.15497267462;56532;488000;489919;492133;493516;493754;28324.85837570799;28966.27584310409;31511.527327089654;31628.683713162573;32366.838165809022;51496.74251628718;52415.21377179422;52475.475245248585;52571;52770.736146319025;44203.11593768171;44470;44642;45970;46780.29829552492;40954;41675.16649666929;42359.15281694287;43350.78324608357;43587.78206109076;43918.12163756771;44643;44859.327110094026;44918;44962.3255651172;36621.450678239315;39259.41110883282;39487.2102557953;39918.2016359677;39941.40087898742;28960.855195723885;29423.075962579984;29952.850235748683;29972.250693732833;29984.85007574948 02:51:37 INFO - PROCESS | 1974 | |2;jquery.html;413.4088253797069;420.58992482329734;421.5363100589351;421.7532743345143;438.74772006096504;19384.806151938494;20845.47886302843;21060.473488162785;21146.471338216554;21564.892175539102;20320.56398637628;20357.592848143027;20580.588388232227;20731.481712957182;20878.68681969768;5899.999999999989;6133.3559976202305;6133.601315914473;6138.079288106797;6139.6009259398;5815.38938411467;5975.760969561213;6005.87988240235;6014.30835453924;6043.6675982820925;5938.376470470603;5939.524838012949;5943.197668314765;5943.4056594340655;5944;5893.793717219876;5965.49293310067;5995.310539287994;5996.22049133612;6000.309964354112;5105.183170692709;5352.732363381815;5354.9196762048605;5356;5361.383440904307;22248;23886.283411497683;24303.392415189584;24894;25137.62293565598;2479.776820086201;2508.134693530729;2511.1462102885107;2590.922272331833;2955.290730224752;9752.853707194397;10120.544575494121;10281.485925703684;10731.731706707316;14644.341004654709;1823.553229458791;1873.8032506586885;1931.8454523638216;1931.9227230910747;2038.3782946201402;77841.61079194624;79675.20324796735;79969.60015199945;83558;88502.55748721279;9599.760005999766;9744.415335079917;10093.848592271122;10185.592576297013;10249.077583017564;13922.164670119826;13939.790903136462;13955.232462214473;13981.440742370394;14060.71878562423;8009.679612815538;8162.959185204036;8164.959175204086;8276.213759692819;8292.212239837207;8292.792680183044;8395.832083358298;8495.503024514524;8503.404761666749;8821.558922053871;4093.91812163761;4120.587941205856;4142.689298302639;4216.494020717472;4220.915581688349;1970.349784571088;2063.473814177405;2068.8241499472715;2078.490769761418;2135.2526615684696;925.9814803703888;992.0228574853763;1020.4234607446768;1024.8206563851445;1117.391021893054;19876.60246795056;20271.391858244275;21129.049192786362;21518.246861359778;21586;993.9403635781877;1002.6941782756184;1055.9894401056044;1058.3755584205355;1070.5289672544056;9732.95133524342;11147.163962702829;11684.70788230284;11766.352850593299;12115.818262726067;549723;552137;562251;565273;582728;73524.44358581949;78155.2184478148;95411;98317.5084124589;103633;1062.1821197677746;1080.400377790334;1089.7983872983523;1098.4892025208317;1101.5208384352832;18494.81505184931;18678.626427471103;19169.233230670616;19180.61638767189;19180.808191917902;34764.957051288504;45188.322175166744;45262.32106518339;45565.31652025156;46906.296405554625;55171;56039.15941260803;65580;66152.33847661654;66969.66515167394;8663.26362259215;8874.511901845202;8946.821063578822;9020.90979090227;9038.728838134866;10704.518296676824;14180.290985450893;14404.351804168627;14408.20754858493;14517.637059073608;11966.401679915794;12086.093542984136;12381.257124572554;12382;12512.249265044127;11949.283043017447;11972.880271197526;12068.396580171131;12136.696582585506;12813.359332033175;12084.818727719266;12209.633710988684;12713.11008229415;12802.871971280169;12964.805527917277;8830.470171789713;8864.86702699447;9003.63985440575;9029.77425564366;9588.616455341988;11989.22070065443;12169;12187.695307617381;12394.938025309815;12439.378031098227 02:51:37 INFO - PROCESS | 1974 | |3;mootools.html;1543.1435553267936;1570.214892553723;1745.2233755978586;1864.1624983168672;1877.4180004198709;9230.63077476901;9965.252606054557;9970.152537034352;9970.750731231723;9993.900060999387;8563.44337618056;9087;9368.953155234214;9421.76445588859;9724.902750972487;2492.3021553964873;2547.0703193334393;2552.4767422678306;2553.872306384683;2554.6423500709916;2496.7128780190237;2570.1004648373105;2577.2139497453268;2580.83224590401;2582.5093232285803;2526.103233352161;2535.8098142639374;2578.355411147213;2579.148880869318;2580.8322459040196;2463.8398504097177;2471.2092130518154;2477.6531285620026;2494.7131079925857;2499.237732491589;2412.0592968742203;2419.5040016796515;2420.5884999549944;2467.5558399488086;2533.784628306596;9642.276829237835;9705.660301889471;9714.708558743177;9717.854232186522;9721.902780972241;5645.379008309083;5777.508911742507;5837.387074357174;5838;5843;3664.8900532983785;3797.9430308545393;3832.9425058624142;3882.514685664292;3971.027098360891;1271.9109662323642;1310.6068179546098;1314.0801438992744;1321.108251929953;1352.91206071606;13864.098833575785;13939.093958892741;14007.229602371866;14061.226632535207;14081.225532595703;4940.925886111711;5027.245913113063;5132.769025393866;5187.351581052369;5230.267762513251;11613.941930290295;11656.88343116575;11662.300261984308;11663.416829158678;11702.941485292518;5764.5676574257095;5772.249607550992;5786.855328616734;5787.479126878517;5791.305043394819;4552.635789136861;5229.450907654642;5241.73791310433;5291.788328466895;5338.439463856317;4320.4383430153875;4381.890452738707;4397.054633253866;4481.775911204427;4483.708558943724;1350.1021820489264;1394.797754325617;1402.6984198397392;1405.2552147361914;1415.7876318552298;1019.4596863662271;1034.2398337222135;1039.1375158618328;1039.8544203811473;1052.4211683574003;6492.610443373413;6521.67391630426;6625.17185351831;6706.396424321834;6742.42689371409;479.3983550643989;485.53631282125485;502.80390647646715;509.34294759760235;529.8436961096409;4457.509673935864;4477.529859364785;4547.045120524661;4612.100640375162;4625.907481850411;11750.764984700258;12143.757124857453;12468.064895132902;12529.686757830945;12538.373081345897;12097.455614497369;12586.81119783204;12640.55758048464;12645.87354126447;12668.493260269486;9037;9190.678326258549;9207.95396023029;9225.077492250855;9273.953630231807;6480.902786458113;6633.402993730394;6642;6703.966480167763;6812.93187068123;11216.943915280372;11581.073514118681;11600.30398176112;11611.303321800719;11641.6507504775;11459.770804583695;11474.885251147381;11835.585754498552;11857.992070674089;11997.220180688228;7759.573223472763;7934.682612695427;8313;8703.825923481621;8867;9278.396904201205;9341.159295663423;9345.112214339626;9350.345475816628;9362.672306469241;7785.338246248905;7860.764177074697;8234.176582341957;8269.669213231404;8476.110008449024;8104.392170587112;8124.55314957683;8124.83750325002;8148.592570371577;8227.753167404988;7256.45576581769;7263.818404539717;7314.08573928259;7334.706611735684;7399.408047356091;5551.639143455663;5557.944420555905;5616;5721.313442386941;5789.536837052941;5831.891940531285;5899.17411562373;5957.136215248851;6096.420840020191;6114 02:51:37 INFO - PROCESS | 1974 | |4;prototype.html;359.0467309293825;359.2402069622748;361.36580301570757;363.2027699200257;366.152357292867;5481.424450432707;5546.362168350636;5547.140193270039;5576.219329293902;5587.580931430144;5439.673619582828;5442.83671489855;5483.588730845183;5535.059039963206;5538.252335934648;4152.16956608678;4271.700980931336;4327.632151267146;4352.782360881959;4365;4301.591348821857;4314.1156063007165;4321.54623764505;4323.848665296715;4329.956700433003;4254.510731265913;4276.294411422112;4290.262443710302;4300.677449191323;4315.848945286915;4176.477940257452;4301.68770047062;4315;4323.437953066113;4357.803898824544;3738.682212011955;3767.6420740029926;3775.169462718198;3777.5645254803276;3808.8666896658483;5312.601554883399;5326.946730532683;5351.411344752074;5355;5367.141257398836;4774.140654682155;4882.803713090316;4884.534639608103;4938.678985865943;4941.98689268699;5651.265335506399;5653.886922261573;5661.915071273934;5667.716614169275;5675.886482270373;4162.250794856925;4192.538820729717;4213.831446742127;4219.493660760729;4229.344451610005;5637.379888212294;5929.9703501482645;5950.345461999176;5965.194698715662;5966.522678185735;4267.27456332418;4277.679174061957;4292.613664770186;4298.20483210607;4300.752781693274;4334.764592091239;4353.978230108829;4354.194474022316;4365.214261432972;4365.493904602907;4812.51874812516;4912.435069966999;4955.157623204064;4960;4962.975185124052;5687.345955215121;5704.172894930211;5722.799702010409;5726.3700992890745;5815.127730840408;1478.1648368671665;1495.3420494982172;1496.6679655106964;1496.7081419123167;1504.247876061969;931.2503434734967;967.5210770668564;971.7424882406168;984.0946329376991;990.7671697151163;4997.25041243809;5009.269947878415;5023.2214006829;5050.318207042076;5066.088104141216;5186.507281808223;5200.271961925334;5212.478752124757;5230.633855630145;5242.449542797951;4913.950860491349;4919.409670839451;4922.8769280768265;5275.366955965309;5645.435456454404;3113.626364836234;3118.2827949571724;3123.2660324823646;3132.0916934089314;3156.210947263184;6116.113163591254;6131.50947924165;6141.477974372139;6147.784827531104;6148.385161483905;5738.454846789548;5781.53747700183;6030.879382412415;6036.438501284263;6065.090236464477;5922.7927022554;6083.931463302499;6096.878062438726;6098.54260930432;6110.541709371815;5911.261092363455;5914.733836977261;5915.615484993462;5918.852028699317;5924.555658325557;5622.915656265078;5867.325257595431;5905.704714764331;5912.172295878494;5913.172155898092;6132.4174203452385;6141.692915354304;6144.907826382697;6156.384361563808;6175.289841668266;4133.793310334531;4325.048489332467;4334.761588112684;4345.676052327954;4371.497277813092;5873.471387575139;5884.617499862496;5888.263967004124;5910.645361278337;5928.407159284037;5733.827985160452;5735.9139612905;5749.8562535936935;5756.94243057564;5775.595708300379;5620.634658747168;5705.315362156568;5715.942840571541;5717.742701578356;5799.913001305068;5725.139329717942;5734.2258795062135;5737.225474560881;5743.840887511935;5745.827625171084;5570.637908535932;5765.59640825155;5774.422557744192;5792.625023740246;5802.912956305743;5628.634138780966;5646.181303711021;5649.576281778801;5653.332266981168;5657.60396772222 02:51:37 INFO - PROCESS | 1974 | |5;yui.html;833.2126140796945;878.4202426398576;886.3441053620321;898.5641963647626;916.3402350307776;15120.16839073852;16389;16403.753943690856;16414.917925410355;16476.67046659066;13871.098378605384;13958.860411395883;13988.930055349709;13993.230372329517;14020.719585608282;746.6490749347824;876.5923845411883;887.4054383563013;891.5675897189858;894.3873446689006;887.0863011098546;887.9378443508957;890.2255038116847;891.8929728432597;892.3084609427713;938.9342746007782;940.1538615246299;940.1773448232797;941.948192849393;942.2556180617338;933.7432206143297;939.68050862707;941.1999800169835;941.3598752848061;941.6515889121035;691.1291772366803;692.0277010799834;692.4875592061838;694.6214313199268;695.8330000799772;14812.481563145346;14824.629384265372;14873.553793386216;14876.107433554022;14878.404863805436;13870.791938120936;14099;14320.212388318638;14540;14766.335514901855;12795.232286062866;12870.227786332756;12957.805632915513;13078.934605327007;13263.668408289774;4012.8194231259663;4041.312976793952;4050.6632811172244;4079.551249362568;4184.497860256789;93910;95718.04281957161;95987.52006239994;96891;99829.0017099827;11841.359209780338;11997.220180688402;12036;12209.206401583868;12218.083643726755;25072.49855002926;25092.874535627205;25167.37081572975;25190.370240744127;25740.356491087874;9272;9596;9601.951990240004;9711.562979665932;9802.901970980342;4885.071836351153;4928.359313289321;4934.753262336869;4986.501349864984;5176;3941.7832019238645;4094.0992981544014;4095.5085389752826;4098.323776576891;4098.692598055158;674.8987651852261;678.993210067893;682.1507223506766;682.8361193313569;685.2804555217002;699.9440044796404;745.9403247740167;801.0094914138361;833.2667252817482;883.6023789294715;9841.655542056136;10121.937196594252;10314.742131446625;10352.171826253882;10865.619703310345;810.3663489924921;818.378032695156;824.826786374868;831.2975535672347;843.6625349859983;4901.264810278487;4919.90160196794;4921;4929.926051109236;5008.474110218447;114123;120018;124285;128341.35829320793;128611.35694321655;107576;110137;110190.44904775424;110488;112360.43819780849;93559.12881742328;95478;95935.52032239795;98332.50833745785;101449;9398.95300523493;9962.501874906371;10057.949710251401;10073.69778906634;10098;45770.62688119362;48644.513554865414;53288.4671153299;53987;54081;83248;84053;85927;86990.13009869821;88356;12109.569406565362;12550.623481295575;12599.244045357307;12675.80986285188;12878.42047107901;14066.296685165496;14168.574942751733;14327.57017289483;14582.708345832812;14643.048201866844;13464.124831885896;13565.575614560723;13724.794128088286;13793.448262069805;13928.66866321832;12875.356232188165;13114;13545.05184637066;13621;13637;11962.58130965412;12751.171173873669;14005.229712365917;14152.78770818418;14811;11137.164712646421;11410.973012428922;11847.466863991314;11941.582044628396;12023.879761202626;11220.775584488429;11265.71835704114;11297.26567773092;11409.442639886454;11545.307281563133 02:51:37 INFO - PROCESS | 1974 | __end_tp_report 02:51:37 INFO - PROCESS | 1974 | __start_cc_report 02:51:37 INFO - PROCESS | 1974 | _x_x_mozilla_cycle_collect,994 02:51:37 INFO - PROCESS | 1974 | __end_cc_report 02:51:37 INFO - PROCESS | 1974 | __startTimestamp1474365097720__endTimestamp 02:51:37 INFO - PROCESS | 1974 | 02:51:37 INFO - PROCESS | 1974 | ------- Summary: start ------- 02:51:37 INFO - PROCESS | 1974 | Number of tests: 6 02:51:37 INFO - PROCESS | 1974 | 02:51:37 INFO - PROCESS | 1974 | [#0] dojo.html Cycles:165 Average:5695.49 Median:5911.35 stddev:658.07 (11.1%) stddev-sans-first:631.87 02:51:37 INFO - PROCESS | 1974 | Values: 3265.5 3275.1 3294.7 3318.8 3330.9 5548.5 5670.9 5671.2 5684.5 5686.7 5682.1 5764.6 5773.3 5774.0 5784.2 5043.5 5224.9 5230.1 5288.3 5310.0 5107.0 5203.0 5221.6 5238.6 5247.8 5084.9 5142.1 5156.3 5157.5 5246.0 5247.6 5247.7 5257.0 5264.1 5295.1 5054.5 5088.6 5109.8 5157.8 5160.5 5626.5 5640.5 5665.8 5667.2 5698.2 6200.6 6212.8 6215.5 6219.6 6225.4 5944.2 6035.2 6051.7 6096.6 6222.6 5739.1 5740.8 5742.7 5755.5 5768.7 6241.6 6267.2 6269.4 6270.3 6278.2 5809.1 6017.1 6080.1 6083.1 6125.0 6194.5 6202.4 6206.8 6207.8 6209.2 6138.8 6172.5 6186.0 6187.2 6188.0 5935.4 6017.0 6067.2 6070.5 6137.3 4656.8 4691.8 4694.3 4715.1 4723.0 4160.7 4176.9 4181.5 4181.9 4209.3 5646.7 5806.7 5830.3 5849.2 5853.5 6198.9 6199.5 6202.1 6209.7 6217.6 5862.3 5877.0 5881.9 5883.6 5911.3 5183.6 5265.9 5280.8 5312.2 5339.4 6409.4 6442.9 6457.6 6463.9 6469.1 6194.5 6262.9 6263.9 6264.4 6272.1 6181.0 6238.1 6239.5 6293.2 6481.5 5851.8 5941.4 5948.0 6041.9 6052.1 6110.5 6111.3 6114.5 6114.8 6115.4 5911.7 5930.7 5954.6 5965.1 5979.3 5757.6 5840.9 5923.4 5982.6 6095.4 6009.5 6013.6 6024.2 6032.8 6032.9 6003.9 6012.9 6053.1 6067.5 6082.5 5699.3 5808.3 5866.9 5871.8 5956.1 02:51:37 INFO - PROCESS | 1974 | 02:51:37 INFO - PROCESS | 1974 | [#1] ext.html Cycles:180 Average:66264.02 Median:39929.80 stddev:136103.60 (340.9%) stddev-sans-first:136396.08 02:51:37 INFO - PROCESS | 1974 | Values: 587.6 640.9 653.8 653.9 654.2 36043.5 46608.5 46853.8 46889.0 46988.5 47882.5 48444.0 48534.8 48563.0 48575.3 8476.2 9382.0 9530.7 9537.4 9539.7 11051.0 11336.7 11343.5 11548.4 11634.9 11448.8 11541.6 11555.7 11561.5 11570.1 11264.3 11304.8 11315.5 11316.9 11329.8 11165.6 11208.7 11225.5 11242.8 11245.3 45585.3 45965.0 46041.5 46074.8 46094.3 3950.1 4093.9 4190.7 4211.2 4433.3 34511.3 34674.7 34802.0 35093.0 35555.3 4791.0 4828.2 4828.5 4935.1 4948.9 361103.0 361756.0 362866.0 363473.0 364085.0 66473.0 66619.0 66950.7 67087.0 67205.3 40209.0 40707.8 40877.0 42975.8 69660.7 18221.4 18354.4 18386.5 18390.8 18401.5 13279.3 13747.2 13788.9 13876.8 13983.8 20711.0 20880.8 21216.6 21221.8 21258.9 7370.5 7543.4 7631.0 7675.4 7779.8 1047.8 1063.8 1119.5 1128.7 1159.1 35343.8 35807.0 35970.0 35975.3 36115.6 1137.8 1168.7 1189.2 1227.5 1247.9 17672.9 18762.3 18812.4 18866.2 18977.0 635482.0 642682.0 643459.0 644023.0 644533.0 42766.8 42807.0 42953.6 42991.8 43085.0 1365.2 1444.4 1478.8 1490.4 1495.2 33630.2 35647.0 35810.6 36111.8 36144.6 55174.7 55951.0 56046.4 56335.2 56532.0 488000.0 489919.0 492133.0 493516.0 493754.0 28324.9 28966.3 31511.5 31628.7 32366.8 51496.7 52415.2 52475.5 52571.0 52770.7 44203.1 44470.0 44642.0 45970.0 46780.3 40954.0 41675.2 42359.2 43350.8 43587.8 43918.1 44643.0 44859.3 44918.0 44962.3 36621.5 39259.4 39487.2 39918.2 39941.4 28960.9 29423.1 29952.9 29972.3 29984.9 02:51:37 INFO - PROCESS | 1974 | 02:51:37 INFO - PROCESS | 1974 | [#2] jquery.html Cycles:180 Average:31456.15 Median:13824.38 stddev:92397.20 (668.4%) stddev-sans-first:92627.00 02:51:37 INFO - PROCESS | 1974 | Values: 413.4 420.6 421.5 421.8 438.7 19384.8 20845.5 21060.5 21146.5 21564.9 20320.6 20357.6 20580.6 20731.5 20878.7 5900.0 6133.4 6133.6 6138.1 6139.6 5815.4 5975.8 6005.9 6014.3 6043.7 5938.4 5939.5 5943.2 5943.4 5944.0 5893.8 5965.5 5995.3 5996.2 6000.3 5105.2 5352.7 5354.9 5356.0 5361.4 22248.0 23886.3 24303.4 24894.0 25137.6 2479.8 2508.1 2511.1 2590.9 2955.3 9752.9 10120.5 10281.5 10731.7 14644.3 1823.6 1873.8 1931.8 1931.9 2038.4 77841.6 79675.2 79969.6 83558.0 88502.6 9599.8 9744.4 10093.8 10185.6 10249.1 13922.2 13939.8 13955.2 13981.4 14060.7 8009.7 8163.0 8165.0 8276.2 8292.2 8292.8 8395.8 8495.5 8503.4 8821.6 4093.9 4120.6 4142.7 4216.5 4220.9 1970.3 2063.5 2068.8 2078.5 2135.3 926.0 992.0 1020.4 1024.8 1117.4 19876.6 20271.4 21129.0 21518.2 21586.0 993.9 1002.7 1056.0 1058.4 1070.5 9733.0 11147.2 11684.7 11766.4 12115.8 549723.0 552137.0 562251.0 565273.0 582728.0 73524.4 78155.2 95411.0 98317.5 103633.0 1062.2 1080.4 1089.8 1098.5 1101.5 18494.8 18678.6 19169.2 19180.6 19180.8 34765.0 45188.3 45262.3 45565.3 46906.3 55171.0 56039.2 65580.0 66152.3 66969.7 8663.3 8874.5 8946.8 9020.9 9038.7 10704.5 14180.3 14404.4 14408.2 14517.6 11966.4 12086.1 12381.3 12382.0 12512.2 11949.3 11972.9 12068.4 12136.7 12813.4 12084.8 12209.6 12713.1 12802.9 12964.8 8830.5 8864.9 9003.6 9029.8 9588.6 11989.2 12169.0 12187.7 12394.9 12439.4 02:51:37 INFO - PROCESS | 1974 | 02:51:37 INFO - PROCESS | 1974 | [#3] mootools.html Cycles:180 Average:6415.33 Median:2552.65 stddev:3741.86 (146.6%) stddev-sans-first:3734.44 02:51:37 INFO - PROCESS | 1974 | Values: 1543.1 1570.2 1745.2 1864.2 1877.4 9230.6 9965.3 9970.2 9970.8 9993.9 8563.4 9087.0 9369.0 9421.8 9724.9 2492.3 2547.1 2552.5 2553.9 2554.6 2496.7 2570.1 2577.2 2580.8 2582.5 2526.1 2535.8 2578.4 2579.1 2580.8 2463.8 2471.2 2477.7 2494.7 2499.2 2412.1 2419.5 2420.6 2467.6 2533.8 9642.3 9705.7 9714.7 9717.9 9721.9 5645.4 5777.5 5837.4 5838.0 5843.0 3664.9 3797.9 3832.9 3882.5 3971.0 1271.9 1310.6 1314.1 1321.1 1352.9 13864.1 13939.1 14007.2 14061.2 14081.2 4940.9 5027.2 5132.8 5187.4 5230.3 11613.9 11656.9 11662.3 11663.4 11702.9 5764.6 5772.2 5786.9 5787.5 5791.3 4552.6 5229.5 5241.7 5291.8 5338.4 4320.4 4381.9 4397.1 4481.8 4483.7 1350.1 1394.8 1402.7 1405.3 1415.8 1019.5 1034.2 1039.1 1039.9 1052.4 6492.6 6521.7 6625.2 6706.4 6742.4 479.4 485.5 502.8 509.3 529.8 4457.5 4477.5 4547.0 4612.1 4625.9 11750.8 12143.8 12468.1 12529.7 12538.4 12097.5 12586.8 12640.6 12645.9 12668.5 9037.0 9190.7 9208.0 9225.1 9274.0 6480.9 6633.4 6642.0 6704.0 6812.9 11216.9 11581.1 11600.3 11611.3 11641.7 11459.8 11474.9 11835.6 11858.0 11997.2 7759.6 7934.7 8313.0 8703.8 8867.0 9278.4 9341.2 9345.1 9350.3 9362.7 7785.3 7860.8 8234.2 8269.7 8476.1 8104.4 8124.6 8124.8 8148.6 8227.8 7256.5 7263.8 7314.1 7334.7 7399.4 5551.6 5557.9 5616.0 5721.3 5789.5 5831.9 5899.2 5957.1 6096.4 6114.0 02:51:37 INFO - PROCESS | 1974 | 02:51:37 INFO - PROCESS | 1974 | [#4] prototype.html Cycles:180 Average:4817.82 Median:5462.13 stddev:1399.47 (25.6%) stddev-sans-first:1362.80 02:51:37 INFO - PROCESS | 1974 | Values: 359.0 359.2 361.4 363.2 366.2 5481.4 5546.4 5547.1 5576.2 5587.6 5439.7 5442.8 5483.6 5535.1 5538.3 4152.2 4271.7 4327.6 4352.8 4365.0 4301.6 4314.1 4321.5 4323.8 4330.0 4254.5 4276.3 4290.3 4300.7 4315.8 4176.5 4301.7 4315.0 4323.4 4357.8 3738.7 3767.6 3775.2 3777.6 3808.9 5312.6 5326.9 5351.4 5355.0 5367.1 4774.1 4882.8 4884.5 4938.7 4942.0 5651.3 5653.9 5661.9 5667.7 5675.9 4162.3 4192.5 4213.8 4219.5 4229.3 5637.4 5930.0 5950.3 5965.2 5966.5 4267.3 4277.7 4292.6 4298.2 4300.8 4334.8 4354.0 4354.2 4365.2 4365.5 4812.5 4912.4 4955.2 4960.0 4963.0 5687.3 5704.2 5722.8 5726.4 5815.1 1478.2 1495.3 1496.7 1496.7 1504.2 931.3 967.5 971.7 984.1 990.8 4997.3 5009.3 5023.2 5050.3 5066.1 5186.5 5200.3 5212.5 5230.6 5242.4 4914.0 4919.4 4922.9 5275.4 5645.4 3113.6 3118.3 3123.3 3132.1 3156.2 6116.1 6131.5 6141.5 6147.8 6148.4 5738.5 5781.5 6030.9 6036.4 6065.1 5922.8 6083.9 6096.9 6098.5 6110.5 5911.3 5914.7 5915.6 5918.9 5924.6 5622.9 5867.3 5905.7 5912.2 5913.2 6132.4 6141.7 6144.9 6156.4 6175.3 4133.8 4325.0 4334.8 4345.7 4371.5 5873.5 5884.6 5888.3 5910.6 5928.4 5733.8 5735.9 5749.9 5756.9 5775.6 5620.6 5705.3 5715.9 5717.7 5799.9 5725.1 5734.2 5737.2 5743.8 5745.8 5570.6 5765.6 5774.4 5792.6 5802.9 5628.6 5646.2 5649.6 5653.3 5657.6 02:51:37 INFO - PROCESS | 1974 | 02:51:37 INFO - PROCESS | 1974 | [#5] yui.html Cycles:180 Average:22750.73 Median:4045.99 stddev:33748.16 (834.1%) stddev-sans-first:33802.71 02:51:37 INFO - PROCESS | 1974 | Values: 833.2 878.4 886.3 898.6 916.3 15120.2 16389.0 16403.8 16414.9 16476.7 13871.1 13958.9 13988.9 13993.2 14020.7 746.6 876.6 887.4 891.6 894.4 887.1 887.9 890.2 891.9 892.3 938.9 940.2 940.2 941.9 942.3 933.7 939.7 941.2 941.4 941.7 691.1 692.0 692.5 694.6 695.8 14812.5 14824.6 14873.6 14876.1 14878.4 13870.8 14099.0 14320.2 14540.0 14766.3 12795.2 12870.2 12957.8 13078.9 13263.7 4012.8 4041.3 4050.7 4079.6 4184.5 93910.0 95718.0 95987.5 96891.0 99829.0 11841.4 11997.2 12036.0 12209.2 12218.1 25072.5 25092.9 25167.4 25190.4 25740.4 9272.0 9596.0 9602.0 9711.6 9802.9 4885.1 4928.4 4934.8 4986.5 5176.0 3941.8 4094.1 4095.5 4098.3 4098.7 674.9 679.0 682.2 682.8 685.3 699.9 745.9 801.0 833.3 883.6 9841.7 10121.9 10314.7 10352.2 10865.6 810.4 818.4 824.8 831.3 843.7 4901.3 4919.9 4921.0 4929.9 5008.5 114123.0 120018.0 124285.0 128341.4 128611.4 107576.0 110137.0 110190.4 110488.0 112360.4 93559.1 95478.0 95935.5 98332.5 101449.0 9399.0 9962.5 10057.9 10073.7 10098.0 45770.6 48644.5 53288.5 53987.0 54081.0 83248.0 84053.0 85927.0 86990.1 88356.0 12109.6 12550.6 12599.2 12675.8 12878.4 14066.3 14168.6 14327.6 14582.7 14643.0 13464.1 13565.6 13724.8 13793.4 13928.7 12875.4 13114.0 13545.1 13621.0 13637.0 11962.6 12751.2 14005.2 14152.8 14811.0 11137.2 11411.0 11847.5 11941.6 12023.9 11220.8 11265.7 11297.3 11409.4 11545.3 02:51:37 INFO - PROCESS | 1974 | -------- Summary: end -------- 02:51:37 INFO - PROCESS | 1974 | 02:51:38 INFO - TEST-INFO | 1974: exit 0 02:51:38 INFO - TEST-OK | dromaeo_css | took 1113328ms 02:51:38 INFO - TEST-START | kraken 02:51:38 INFO - operating with platform_type : linux_ 02:51:38 INFO - Initialising browser for kraken test... 02:51:38 INFO - TEST-INFO | started process 2166 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH0Ep9i/profile http://localhost:51903/getInfo.html) 02:51:42 INFO - PROCESS | 2166 | __metrics Screen width/height:1600/1200 02:51:42 INFO - PROCESS | 2166 | colorDepth:24 02:51:42 INFO - PROCESS | 2166 | Browser inner width/height: 1024/697 02:51:42 INFO - PROCESS | 2166 | __metrics 02:51:43 INFO - PROCESS | 2166 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:51:43 INFO - PROCESS | 2166 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:51:43 INFO - PROCESS | 2166 | console.error: PushService: 02:51:43 INFO - PROCESS | 2166 | stateChangeProcessEnqueue: Error transitioning state 02:51:43 INFO - PROCESS | 2166 | UnknownError 02:51:44 INFO - TEST-INFO | 2166: exit 0 02:51:44 INFO - Browser initialized. 02:51:44 INFO - Running cycle 1/1 for kraken test... 02:51:44 INFO - TEST-INFO | started process 2258 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH0Ep9i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:51:56 INFO - PROCESS | 2258 | RSS: Main: 143147008 02:51:56 INFO - PROCESS | 2258 | 02:51:58 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51903/tests/kraken/driver.html?testName=audio-beat-detection) 02:51:59 INFO - PROCESS | 2258 | RSS: Main: 198520832 02:51:59 INFO - PROCESS | 2258 | 02:52:02 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51903/tests/kraken/driver.html?testName=audio-dft) 02:52:02 INFO - PROCESS | 2258 | RSS: Main: 182943744 02:52:02 INFO - PROCESS | 2258 | 02:52:05 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51903/tests/kraken/driver.html?testName=audio-fft) 02:52:06 INFO - PROCESS | 2258 | RSS: Main: 273833984 02:52:06 INFO - PROCESS | 2258 | 02:52:08 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51903/tests/kraken/driver.html?testName=audio-oscillator) 02:52:08 INFO - PROCESS | 2258 | RSS: Main: 179945472 02:52:08 INFO - PROCESS | 2258 | 02:52:10 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51903/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:52:11 INFO - PROCESS | 2258 | RSS: Main: 178274304 02:52:11 INFO - PROCESS | 2258 | 02:52:14 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51903/tests/kraken/driver.html?testName=imaging-darkroom) 02:52:15 INFO - PROCESS | 2258 | RSS: Main: 214585344 02:52:15 INFO - PROCESS | 2258 | 02:52:18 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51903/tests/kraken/driver.html?testName=imaging-desaturate) 02:52:19 INFO - PROCESS | 2258 | RSS: Main: 213909504 02:52:19 INFO - PROCESS | 2258 | 02:52:21 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51903/tests/kraken/driver.html?testName=json-parse-financial) 02:52:22 INFO - PROCESS | 2258 | RSS: Main: 213409792 02:52:22 INFO - PROCESS | 2258 | 02:52:23 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51903/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:52:24 INFO - PROCESS | 2258 | RSS: Main: 175349760 02:52:24 INFO - PROCESS | 2258 | 02:52:26 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:52:26 INFO - PROCESS | 2258 | RSS: Main: 182779904 02:52:26 INFO - PROCESS | 2258 | 02:52:28 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:52:29 INFO - PROCESS | 2258 | RSS: Main: 185032704 02:52:29 INFO - PROCESS | 2258 | 02:52:31 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:52:32 INFO - PROCESS | 2258 | RSS: Main: 184922112 02:52:32 INFO - PROCESS | 2258 | 02:52:35 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:52:35 INFO - PROCESS | 2258 | RSS: Main: 182628352 02:52:35 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | Cycle 1(1): loaded http://localhost:51903/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51903/tests/kraken/driver.html?testName=ai-astar) 02:52:37 INFO - PROCESS | 2258 | RSS: Main: 233172992 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | __start_tp_report 02:52:37 INFO - PROCESS | 2258 | _x_x_mozilla_page_load 02:52:37 INFO - PROCESS | 2258 | _x_x_mozilla_page_load_details 02:52:37 INFO - PROCESS | 2258 | |i|pagename|runs| 02:52:37 INFO - PROCESS | 2258 | |0;ai-astar;109;109;119;109;109;109;109;112;110;110 02:52:37 INFO - PROCESS | 2258 | |1;audio-beat-detection;148;118;140;183;116;153;139;118;176;122 02:52:37 INFO - PROCESS | 2258 | |2;audio-dft;160;159;160;160;161;161;161;161;160;160 02:52:37 INFO - PROCESS | 2258 | |3;audio-fft;75;76;74;188;75;75;74;75;163;75 02:52:37 INFO - PROCESS | 2258 | |4;audio-oscillator;89;85;156;83;83;84;148;85;84;140 02:52:37 INFO - PROCESS | 2258 | |5;imaging-gaussian-blur;118;117;116;116;116;116;116;116;117;117 02:52:37 INFO - PROCESS | 2258 | |6;imaging-darkroom;147;146;146;146;146;146;146;146;147;147 02:52:37 INFO - PROCESS | 2258 | |7;imaging-desaturate;95;94;96;94;95;94;94;95;94;94 02:52:37 INFO - PROCESS | 2258 | |8;json-parse-financial;49;49;54;49;50;50;51;49;51;51 02:52:37 INFO - PROCESS | 2258 | |9;json-stringify-tinderbox;59;62;59;60;60;59;60;59;60;60 02:52:37 INFO - PROCESS | 2258 | |10;stanford-crypto-aes;79;75;73;74;82;71;77;78;73;72 02:52:37 INFO - PROCESS | 2258 | |11;stanford-crypto-ccm;128;116;116;118;114;116;116;122;154;117 02:52:37 INFO - PROCESS | 2258 | |12;stanford-crypto-pbkdf2;153;155;152;155;151;153;153;151;153;151 02:52:37 INFO - PROCESS | 2258 | |13;stanford-crypto-sha256-iterative;63;61;62;61;68;62;61;63;61;60 02:52:37 INFO - PROCESS | 2258 | __end_tp_report 02:52:37 INFO - PROCESS | 2258 | __start_cc_report 02:52:37 INFO - PROCESS | 2258 | _x_x_mozilla_cycle_collect,1668 02:52:37 INFO - PROCESS | 2258 | __end_cc_report 02:52:37 INFO - PROCESS | 2258 | __startTimestamp1474365157541__endTimestamp 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | ------- Summary: start ------- 02:52:37 INFO - PROCESS | 2258 | Number of tests: 14 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#0] ai-astar Cycles:10 Average:110.50 Median:109.50 stddev:3.14 (2.9%) stddev-sans-first:3.28 02:52:37 INFO - PROCESS | 2258 | Values: 109.0 109.0 119.0 109.0 109.0 109.0 109.0 112.0 110.0 110.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#1] audio-beat-detection Cycles:10 Average:141.30 Median:144.00 stddev:24.11 (16.7%) stddev-sans-first:25.45 02:52:37 INFO - PROCESS | 2258 | Values: 148.0 118.0 140.0 183.0 116.0 153.0 139.0 118.0 176.0 122.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.50 stddev:0.67 (0.4%) stddev-sans-first:0.71 02:52:37 INFO - PROCESS | 2258 | Values: 160.0 159.0 160.0 160.0 161.0 161.0 161.0 161.0 160.0 160.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#3] audio-fft Cycles:10 Average:95.00 Median:75.00 stddev:42.84 (57.1%) stddev-sans-first:44.82 02:52:37 INFO - PROCESS | 2258 | Values: 75.0 76.0 74.0 188.0 75.0 75.0 74.0 75.0 163.0 75.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#4] audio-oscillator Cycles:10 Average:103.70 Median:84.00 stddev:30.85 (36.7%) stddev-sans-first:32.26 02:52:37 INFO - PROCESS | 2258 | Values: 89.0 85.0 156.0 83.0 83.0 84.0 148.0 85.0 84.0 140.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:116.50 stddev:0.71 (0.6%) stddev-sans-first:0.50 02:52:37 INFO - PROCESS | 2258 | Values: 118.0 117.0 116.0 116.0 116.0 116.0 116.0 116.0 117.0 117.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 02:52:37 INFO - PROCESS | 2258 | Values: 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 147.0 147.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 02:52:37 INFO - PROCESS | 2258 | Values: 95.0 94.0 96.0 94.0 95.0 94.0 94.0 95.0 94.0 94.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:1.57 (3.1%) stddev-sans-first:1.59 02:52:37 INFO - PROCESS | 2258 | Values: 49.0 49.0 54.0 49.0 50.0 50.0 51.0 49.0 51.0 51.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.92 (1.5%) stddev-sans-first:0.93 02:52:37 INFO - PROCESS | 2258 | Values: 59.0 62.0 59.0 60.0 60.0 59.0 60.0 59.0 60.0 60.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#10] stanford-crypto-aes Cycles:10 Average:75.40 Median:76.00 stddev:3.50 (4.6%) stddev-sans-first:3.46 02:52:37 INFO - PROCESS | 2258 | Values: 79.0 75.0 73.0 74.0 82.0 71.0 77.0 78.0 73.0 72.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#11] stanford-crypto-ccm Cycles:10 Average:121.70 Median:117.50 stddev:12.06 (10.3%) stddev-sans-first:12.57 02:52:37 INFO - PROCESS | 2258 | Values: 128.0 116.0 116.0 118.0 114.0 116.0 116.0 122.0 154.0 117.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.70 Median:153.00 stddev:1.49 (1.0%) stddev-sans-first:1.58 02:52:37 INFO - PROCESS | 2258 | Values: 153.0 155.0 152.0 155.0 151.0 153.0 153.0 151.0 153.0 151.0 02:52:37 INFO - PROCESS | 2258 | 02:52:37 INFO - PROCESS | 2258 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:2.25 (3.6%) stddev-sans-first:2.37 02:52:37 INFO - PROCESS | 2258 | Values: 63.0 61.0 62.0 61.0 68.0 62.0 61.0 63.0 61.0 60.0 02:52:37 INFO - PROCESS | 2258 | -------- Summary: end -------- 02:52:37 INFO - PROCESS | 2258 | 02:52:38 INFO - TEST-INFO | 2258: exit 0 02:52:38 INFO - TEST-OK | kraken | took 60068ms 02:52:38 INFO - SUITE-END | took 1173s 02:52:38 INFO - Completed test suite (00:19:33) 02:52:38 INFO - javascript benchmark 02:52:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3265.4611989021814, 3275.115718755935, 3294.7364210863147, 3318.817465039425, 3330.866765329387, 5548.500634942854, 5670.858228544288, 5671.2060311556315, 5684.54523638109, 5686.744096515657, 5682.147677848325, 5764.567657425689, 5773.336066352371, 5773.971130144343, 5784.219130417392, 5043.470435604264, 5224.895502089956, 5230.1108811502045, 5288.286081379003, 5309.973450132744, 5107.029664363778, 5202.973985130069, 5221.556167725748, 5238.607104467161, 5247.763850626712, 5084.949150508503, 5142.07442660321, 5156.252343410203, 5157.5100365465405, 5246.0, 5247.580193584504, 5247.658902171365, 5257.0274499217385, 5264.1051021326275, 5295.0998330283555, 5054.469280725544, 5088.643794934356, 5109.795608175668, 5157.767900444489, 5160.509751573594, 5626.549876009909, 5640.464155905182, 5665.773369065232, 5667.178259152441, 5698.230738850285, 6200.565960382775, 6212.81361559154, 6215.502759779207, 6219.595726277777, 6225.377462253784, 5944.189887713079, 6035.185249991239, 6051.697415129226, 6096.603720758137, 6222.5644204905675, 5739.110437882135, 5740.799072032459, 5742.655440673573, 5755.539556835485, 5768.74040668171, 6241.550809875124, 6267.247930248308, 6269.373062693785, 6270.310265870842, 6278.183836101278, 5809.099589563624, 6017.1275165100815, 6080.14877917092, 6083.117947897618, 6124.991279909863, 6194.5354098442795, 6202.41077097675, 6206.751729930843, 6207.81376558704, 6209.192804935329, 6138.846528836725, 6172.475339596184, 6186.0, 6187.195664563578, 6187.969060154671, 5935.376785437553, 6016.969915150396, 6067.211262535842, 6070.514358851281, 6137.285055221507, 4656.8137274508645, 4691.812327506861, 4694.319323698113, 4715.080559290974, 4723.0, 4160.667146628323, 4176.937345939813, 4181.477315335583, 4181.853635122756, 4209.284421648327, 5646.6894320811925, 5806.68063256525, 5830.271216097988, 5849.239598852122, 5853.5317174625925, 6198.876022479615, 6199.504039676815, 6202.069689546604, 6209.689515524296, 6217.595856269328, 5862.325832529228, 5877.0, 5881.941180588225, 5883.588148829541, 5911.349751527328, 5183.5593974512585, 5265.894682106336, 5280.788768449219, 5312.203169524602, 5339.439358867341, 6409.359064093554, 6442.903356449563, 6457.644829534421, 6463.935360646427, 6469.061986012002, 6194.5044396449075, 6262.874742505033, 6263.906041409474, 6264.373562643699, 6272.090546870587, 6181.041938499539, 6238.064290356483, 6239.469645080219, 6293.213348331458, 6481.5138864584405, 5851.795187168429, 5941.37615550361, 5947.9702601486715, 6041.909371359521, 6052.122442245938, 6110.51115910735, 6111.327753946974, 6114.541409394402, 6114.847128821815, 6115.388461153849, 5911.674857882857, 5930.673812940329, 5954.642721436728, 5965.135055417027, 5979.3123790762875, 5757.596968212185, 5840.883182336244, 5923.4076592340425, 5982.551308651781, 6095.35998720128, 6009.45914867664, 6013.639181649115, 6024.186734790747, 6032.788852390146, 6032.909506357496, 6003.87992240144, 6012.909806352821, 6053.09203619443, 6067.453929146398, 6082.5438092142385, 5699.316082070178, 5808.273965754281, 5866.911996320144, 5871.7651293947765, 5956.13636022783], "value": 5650.36572644722, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.6092398554961, 640.9294977552469, 653.8201994451524, 653.865957478717, 654.2312782480584, 36043.45934810977, 46608.533914660846, 46853.7657311713, 46889.0, 46988.53011469884, 47882.52117478824, 48444.0, 48534.75732621341, 48563.02873942528, 48575.27137092946, 8476.23713865752, 9381.967983521825, 9530.666426675032, 9537.427754334762, 9539.713808585753, 11051.0, 11336.659900203007, 11343.546258149665, 11548.422578871065, 11634.883651163465, 11448.828267575993, 11541.596044138458, 11555.653330400059, 11561.479733412016, 11570.074394048497, 11264.267822591506, 11304.773904521864, 11315.490802913888, 11316.886831131747, 11329.773404531945, 11165.553377864875, 11208.66374008781, 11225.494852731646, 11242.831357529562, 11245.269057511317, 45585.316220256726, 45965.0, 46041.53958460406, 46074.76962615199, 46094.30858537125, 3950.051987522974, 4093.9385909211387, 4190.664746820247, 4211.1998720243055, 4433.334999750031, 34511.30977380438, 34674.6532534674, 34802.0, 35093.0, 35555.28889422197, 4790.952090479085, 4828.203346447832, 4828.49300823412, 4935.111679897617, 4948.925766113512, 361103.0, 361756.0, 362866.0, 363473.0, 364084.9999999973, 66473.0, 66619.0, 66950.66524667345, 67087.0, 67205.32794672088, 40209.0, 40707.796461017504, 40877.0, 42975.77681673887, 69660.6516967422, 18221.362252321105, 18354.449366519028, 18386.540336491697, 18390.816091839177, 18401.539961500803, 13279.26964016969, 13747.175169489863, 13788.93105534466, 13876.791848122286, 13983.79024314636, 20711.0, 20880.791192087883, 21216.575668486854, 21221.78778212198, 21258.893705531373, 7370.520916140434, 7543.396528277724, 7631.0, 7675.424343174284, 7779.844403111906, 1047.7793370723175, 1063.7659714862718, 1119.5353928119903, 1128.6726849213635, 1159.1306520109918, 35343.82328088343, 35807.0, 35970.0, 35975.28049439049, 36115.638843611756, 1137.7724455109012, 1168.696139003865, 1189.191349882071, 1227.4967263421954, 1247.9126461147632, 17672.911635441742, 18762.343317983807, 18812.435626931216, 18866.151023203714, 18977.0, 635482.0, 642682.0, 643459.0, 644023.0, 644533.0, 42766.78616606959, 42807.0, 42953.57046429496, 42991.785041074596, 43085.0, 1365.215001374194, 1444.4366696988184, 1478.8225412950515, 1490.3889405343973, 1495.2338174377533, 33630.159246019044, 35647.0, 35810.64189358073, 36111.81944090368, 36144.638553614124, 55174.724126379115, 55951.0, 56046.43953560575, 56335.15497267462, 56532.0, 488000.0, 489919.0, 492133.0, 493516.0, 493754.0, 28324.85837570799, 28966.27584310409, 31511.527327089654, 31628.683713162573, 32366.838165809022, 51496.74251628718, 52415.21377179422, 52475.475245248585, 52571.0, 52770.736146319025, 44203.11593768171, 44470.0, 44642.0, 45970.0, 46780.29829552492, 40954.0, 41675.16649666929, 42359.15281694287, 43350.78324608357, 43587.78206109076, 43918.12163756771, 44643.0, 44859.327110094026, 44918.0, 44962.3255651172, 36621.450678239315, 39259.41110883282, 39487.2102557953, 39918.2016359677, 39941.40087898742, 28960.855195723885, 29423.075962579984, 29952.850235748683, 29972.250693732833, 29984.85007574948], "value": 21882.088791758117, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.4088253797069, 420.58992482329734, 421.5363100589351, 421.7532743345143, 438.74772006096504, 19384.806151938494, 20845.47886302843, 21060.473488162785, 21146.471338216554, 21564.892175539102, 20320.56398637628, 20357.592848143027, 20580.588388232227, 20731.481712957182, 20878.68681969768, 5899.999999999989, 6133.3559976202305, 6133.601315914473, 6138.079288106797, 6139.6009259398, 5815.38938411467, 5975.760969561213, 6005.87988240235, 6014.30835453924, 6043.6675982820925, 5938.376470470603, 5939.524838012949, 5943.197668314765, 5943.4056594340655, 5944.0, 5893.793717219876, 5965.49293310067, 5995.310539287994, 5996.22049133612, 6000.309964354112, 5105.183170692709, 5352.732363381815, 5354.9196762048605, 5356.0, 5361.383440904307, 22248.0, 23886.283411497683, 24303.392415189584, 24894.0, 25137.62293565598, 2479.776820086201, 2508.134693530729, 2511.1462102885107, 2590.922272331833, 2955.290730224752, 9752.853707194397, 10120.544575494121, 10281.485925703684, 10731.731706707316, 14644.341004654709, 1823.553229458791, 1873.8032506586885, 1931.8454523638216, 1931.9227230910747, 2038.3782946201402, 77841.61079194624, 79675.20324796735, 79969.60015199945, 83558.0, 88502.55748721279, 9599.760005999766, 9744.415335079917, 10093.848592271122, 10185.592576297013, 10249.077583017564, 13922.164670119826, 13939.790903136462, 13955.232462214473, 13981.440742370394, 14060.71878562423, 8009.679612815538, 8162.959185204036, 8164.959175204086, 8276.213759692819, 8292.212239837207, 8292.792680183044, 8395.832083358298, 8495.503024514524, 8503.404761666749, 8821.558922053871, 4093.91812163761, 4120.587941205856, 4142.689298302639, 4216.494020717472, 4220.915581688349, 1970.349784571088, 2063.473814177405, 2068.8241499472715, 2078.490769761418, 2135.2526615684696, 925.9814803703888, 992.0228574853763, 1020.4234607446768, 1024.8206563851445, 1117.391021893054, 19876.60246795056, 20271.391858244275, 21129.049192786362, 21518.246861359778, 21586.0, 993.9403635781877, 1002.6941782756184, 1055.9894401056044, 1058.3755584205355, 1070.5289672544056, 9732.95133524342, 11147.163962702829, 11684.70788230284, 11766.352850593299, 12115.818262726067, 549723.0, 552137.0, 562251.0, 565273.0, 582728.0, 73524.44358581949, 78155.2184478148, 95411.0, 98317.5084124589, 103633.0, 1062.1821197677746, 1080.400377790334, 1089.7983872983523, 1098.4892025208317, 1101.5208384352832, 18494.81505184931, 18678.626427471103, 19169.233230670616, 19180.61638767189, 19180.808191917902, 34764.957051288504, 45188.322175166744, 45262.32106518339, 45565.31652025156, 46906.296405554625, 55171.0, 56039.15941260803, 65580.0, 66152.33847661654, 66969.66515167394, 8663.26362259215, 8874.511901845202, 8946.821063578822, 9020.90979090227, 9038.728838134866, 10704.518296676824, 14180.290985450893, 14404.351804168627, 14408.20754858493, 14517.637059073608, 11966.401679915794, 12086.093542984136, 12381.257124572554, 12382.0, 12512.249265044127, 11949.283043017447, 11972.880271197526, 12068.396580171131, 12136.696582585506, 12813.359332033175, 12084.818727719266, 12209.633710988684, 12713.11008229415, 12802.871971280169, 12964.805527917277, 8830.470171789713, 8864.86702699447, 9003.63985440575, 9029.77425564366, 9588.616455341988, 11989.22070065443, 12169.0, 12187.695307617381, 12394.938025309815, 12439.378031098227], "value": 9563.346020588315, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.1435553267936, 1570.214892553723, 1745.2233755978586, 1864.1624983168672, 1877.4180004198709, 9230.63077476901, 9965.252606054557, 9970.152537034352, 9970.750731231723, 9993.900060999387, 8563.44337618056, 9087.0, 9368.953155234214, 9421.76445588859, 9724.902750972487, 2492.3021553964873, 2547.0703193334393, 2552.4767422678306, 2553.872306384683, 2554.6423500709916, 2496.7128780190237, 2570.1004648373105, 2577.2139497453268, 2580.83224590401, 2582.5093232285803, 2526.103233352161, 2535.8098142639374, 2578.355411147213, 2579.148880869318, 2580.8322459040196, 2463.8398504097177, 2471.2092130518154, 2477.6531285620026, 2494.7131079925857, 2499.237732491589, 2412.0592968742203, 2419.5040016796515, 2420.5884999549944, 2467.5558399488086, 2533.784628306596, 9642.276829237835, 9705.660301889471, 9714.708558743177, 9717.854232186522, 9721.902780972241, 5645.379008309083, 5777.508911742507, 5837.387074357174, 5838.0, 5843.0, 3664.8900532983785, 3797.9430308545393, 3832.9425058624142, 3882.514685664292, 3971.027098360891, 1271.9109662323642, 1310.6068179546098, 1314.0801438992744, 1321.108251929953, 1352.91206071606, 13864.098833575785, 13939.093958892741, 14007.229602371866, 14061.226632535207, 14081.225532595703, 4940.925886111711, 5027.245913113063, 5132.769025393866, 5187.351581052369, 5230.267762513251, 11613.941930290295, 11656.88343116575, 11662.300261984308, 11663.416829158678, 11702.941485292518, 5764.5676574257095, 5772.249607550992, 5786.855328616734, 5787.479126878517, 5791.305043394819, 4552.635789136861, 5229.450907654642, 5241.73791310433, 5291.788328466895, 5338.439463856317, 4320.4383430153875, 4381.890452738707, 4397.054633253866, 4481.775911204427, 4483.708558943724, 1350.1021820489264, 1394.797754325617, 1402.6984198397392, 1405.2552147361914, 1415.7876318552298, 1019.4596863662271, 1034.2398337222135, 1039.1375158618328, 1039.8544203811473, 1052.4211683574003, 6492.610443373413, 6521.67391630426, 6625.17185351831, 6706.396424321834, 6742.42689371409, 479.3983550643989, 485.53631282125485, 502.80390647646715, 509.34294759760235, 529.8436961096409, 4457.509673935864, 4477.529859364785, 4547.045120524661, 4612.100640375162, 4625.907481850411, 11750.764984700258, 12143.757124857453, 12468.064895132902, 12529.686757830945, 12538.373081345897, 12097.455614497369, 12586.81119783204, 12640.55758048464, 12645.87354126447, 12668.493260269486, 9037.0, 9190.678326258549, 9207.95396023029, 9225.077492250855, 9273.953630231807, 6480.902786458113, 6633.402993730394, 6642.0, 6703.966480167763, 6812.93187068123, 11216.943915280372, 11581.073514118681, 11600.30398176112, 11611.303321800719, 11641.6507504775, 11459.770804583695, 11474.885251147381, 11835.585754498552, 11857.992070674089, 11997.220180688228, 7759.573223472763, 7934.682612695427, 8313.0, 8703.825923481621, 8867.0, 9278.396904201205, 9341.159295663423, 9345.112214339626, 9350.345475816628, 9362.672306469241, 7785.338246248905, 7860.764177074697, 8234.176582341957, 8269.669213231404, 8476.110008449024, 8104.392170587112, 8124.55314957683, 8124.83750325002, 8148.592570371577, 8227.753167404988, 7256.45576581769, 7263.818404539717, 7314.08573928259, 7334.706611735684, 7399.408047356091, 5551.639143455663, 5557.944420555905, 5616.0, 5721.313442386941, 5789.536837052941, 5831.891940531285, 5899.17411562373, 5957.136215248851, 6096.420840020191, 6114.0], "value": 5023.131188744703, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.0467309293825, 359.2402069622748, 361.36580301570757, 363.2027699200257, 366.152357292867, 5481.424450432707, 5546.362168350636, 5547.140193270039, 5576.219329293902, 5587.580931430144, 5439.673619582828, 5442.83671489855, 5483.588730845183, 5535.059039963206, 5538.252335934648, 4152.16956608678, 4271.700980931336, 4327.632151267146, 4352.782360881959, 4365.0, 4301.591348821857, 4314.1156063007165, 4321.54623764505, 4323.848665296715, 4329.956700433003, 4254.510731265913, 4276.294411422112, 4290.262443710302, 4300.677449191323, 4315.848945286915, 4176.477940257452, 4301.68770047062, 4315.0, 4323.437953066113, 4357.803898824544, 3738.682212011955, 3767.6420740029926, 3775.169462718198, 3777.5645254803276, 3808.8666896658483, 5312.601554883399, 5326.946730532683, 5351.411344752074, 5355.0, 5367.141257398836, 4774.140654682155, 4882.803713090316, 4884.534639608103, 4938.678985865943, 4941.98689268699, 5651.265335506399, 5653.886922261573, 5661.915071273934, 5667.716614169275, 5675.886482270373, 4162.250794856925, 4192.538820729717, 4213.831446742127, 4219.493660760729, 4229.344451610005, 5637.379888212294, 5929.9703501482645, 5950.345461999176, 5965.194698715662, 5966.522678185735, 4267.27456332418, 4277.679174061957, 4292.613664770186, 4298.20483210607, 4300.752781693274, 4334.764592091239, 4353.978230108829, 4354.194474022316, 4365.214261432972, 4365.493904602907, 4812.51874812516, 4912.435069966999, 4955.157623204064, 4960.0, 4962.975185124052, 5687.345955215121, 5704.172894930211, 5722.799702010409, 5726.3700992890745, 5815.127730840408, 1478.1648368671665, 1495.3420494982172, 1496.6679655106964, 1496.7081419123167, 1504.247876061969, 931.2503434734967, 967.5210770668564, 971.7424882406168, 984.0946329376991, 990.7671697151163, 4997.25041243809, 5009.269947878415, 5023.2214006829, 5050.318207042076, 5066.088104141216, 5186.507281808223, 5200.271961925334, 5212.478752124757, 5230.633855630145, 5242.449542797951, 4913.950860491349, 4919.409670839451, 4922.8769280768265, 5275.366955965309, 5645.435456454404, 3113.626364836234, 3118.2827949571724, 3123.2660324823646, 3132.0916934089314, 3156.210947263184, 6116.113163591254, 6131.50947924165, 6141.477974372139, 6147.784827531104, 6148.385161483905, 5738.454846789548, 5781.53747700183, 6030.879382412415, 6036.438501284263, 6065.090236464477, 5922.7927022554, 6083.931463302499, 6096.878062438726, 6098.54260930432, 6110.541709371815, 5911.261092363455, 5914.733836977261, 5915.615484993462, 5918.852028699317, 5924.555658325557, 5622.915656265078, 5867.325257595431, 5905.704714764331, 5912.172295878494, 5913.172155898092, 6132.4174203452385, 6141.692915354304, 6144.907826382697, 6156.384361563808, 6175.289841668266, 4133.793310334531, 4325.048489332467, 4334.761588112684, 4345.676052327954, 4371.497277813092, 5873.471387575139, 5884.617499862496, 5888.263967004124, 5910.645361278337, 5928.407159284037, 5733.827985160452, 5735.9139612905, 5749.8562535936935, 5756.94243057564, 5775.595708300379, 5620.634658747168, 5705.315362156568, 5715.942840571541, 5717.742701578356, 5799.913001305068, 5725.139329717942, 5734.2258795062135, 5737.225474560881, 5743.840887511935, 5745.827625171084, 5570.637908535932, 5765.59640825155, 5774.422557744192, 5792.625023740246, 5802.912956305743, 5628.634138780966, 5646.181303711021, 5649.576281778801, 5653.332266981168, 5657.60396772222], "value": 4377.774369361959, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [833.2126140796945, 878.4202426398576, 886.3441053620321, 898.5641963647626, 916.3402350307776, 15120.16839073852, 16389.0, 16403.753943690856, 16414.917925410355, 16476.67046659066, 13871.098378605384, 13958.860411395883, 13988.930055349709, 13993.230372329517, 14020.719585608282, 746.6490749347824, 876.5923845411883, 887.4054383563013, 891.5675897189858, 894.3873446689006, 887.0863011098546, 887.9378443508957, 890.2255038116847, 891.8929728432597, 892.3084609427713, 938.9342746007782, 940.1538615246299, 940.1773448232797, 941.948192849393, 942.2556180617338, 933.7432206143297, 939.68050862707, 941.1999800169835, 941.3598752848061, 941.6515889121035, 691.1291772366803, 692.0277010799834, 692.4875592061838, 694.6214313199268, 695.8330000799772, 14812.481563145346, 14824.629384265372, 14873.553793386216, 14876.107433554022, 14878.404863805436, 13870.791938120936, 14099.0, 14320.212388318638, 14540.0, 14766.335514901855, 12795.232286062866, 12870.227786332756, 12957.805632915513, 13078.934605327007, 13263.668408289774, 4012.8194231259663, 4041.312976793952, 4050.6632811172244, 4079.551249362568, 4184.497860256789, 93910.0, 95718.04281957161, 95987.52006239994, 96891.0, 99829.0017099827, 11841.359209780338, 11997.220180688402, 12036.0, 12209.206401583868, 12218.083643726755, 25072.49855002926, 25092.874535627205, 25167.37081572975, 25190.370240744127, 25740.356491087874, 9272.0, 9596.0, 9601.951990240004, 9711.562979665932, 9802.901970980342, 4885.071836351153, 4928.359313289321, 4934.753262336869, 4986.501349864984, 5176.0, 3941.7832019238645, 4094.0992981544014, 4095.5085389752826, 4098.323776576891, 4098.692598055158, 674.8987651852261, 678.993210067893, 682.1507223506766, 682.8361193313569, 685.2804555217002, 699.9440044796404, 745.9403247740167, 801.0094914138361, 833.2667252817482, 883.6023789294715, 9841.655542056136, 10121.937196594252, 10314.742131446625, 10352.171826253882, 10865.619703310345, 810.3663489924921, 818.378032695156, 824.826786374868, 831.2975535672347, 843.6625349859983, 4901.264810278487, 4919.90160196794, 4921.0, 4929.926051109236, 5008.474110218447, 114123.0, 120018.0, 124285.0, 128341.35829320793, 128611.35694321655, 107576.0, 110137.0, 110190.44904775424, 110488.0, 112360.43819780849, 93559.12881742328, 95478.0, 95935.52032239795, 98332.50833745785, 101449.0, 9398.95300523493, 9962.501874906371, 10057.949710251401, 10073.69778906634, 10098.0, 45770.62688119362, 48644.513554865414, 53288.4671153299, 53987.0, 54081.0, 83248.0, 84053.0, 85927.0, 86990.13009869821, 88356.0, 12109.569406565362, 12550.623481295575, 12599.244045357307, 12675.80986285188, 12878.42047107901, 14066.296685165496, 14168.574942751733, 14327.57017289483, 14582.708345832812, 14643.048201866844, 13464.124831885896, 13565.575614560723, 13724.794128088286, 13793.448262069805, 13928.66866321832, 12875.356232188165, 13114.0, 13545.05184637066, 13621.0, 13637.0, 11962.58130965412, 12751.171173873669, 14005.229712365917, 14152.78770818418, 14811.0, 11137.164712646421, 11410.973012428922, 11847.466863991314, 11941.582044628396, 12023.879761202626, 11220.775584488429, 11265.71835704114, 11297.26567773092, 11409.442639886454, 11545.307281563133], "value": 8079.759279107366, "unit": "score"}], "name": "dromaeo_css", "value": 7710.351576176171, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 109.0, 119.0, 109.0, 109.0, 109.0, 109.0, 112.0, 110.0, 110.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 140.0, 183.0, 116.0, 153.0, 139.0, 118.0, 176.0, 122.0], "value": 141.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 160.0, 160.0, 161.0, 161.0, 161.0, 161.0, 160.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 188.0, 75.0, 75.0, 74.0, 75.0, 163.0, 75.0], "value": 95.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 156.0, 83.0, 83.0, 84.0, 148.0, 85.0, 84.0, 140.0], "value": 103.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 117.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 96.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 54.0, 49.0, 50.0, 50.0, 51.0, 49.0, 51.0, 51.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 59.0, 60.0, 60.0, 59.0, 60.0, 59.0, 60.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 75.0, 73.0, 74.0, 82.0, 71.0, 77.0, 78.0, 73.0, 72.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 116.0, 116.0, 118.0, 114.0, 116.0, 116.0, 122.0, 154.0, 117.0], "value": 121.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 155.0, 152.0, 155.0, 151.0, 153.0, 153.0, 151.0, 153.0, 151.0], "value": 152.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 62.0, 61.0, 68.0, 62.0, 61.0, 63.0, 61.0, 60.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1490.2000000000003, "alertThreshold": 2.0}]} 02:52:38 INFO - Return code: 0 02:52:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:52:38 INFO - # TBPL SUCCESS # 02:52:38 INFO - Running post-action listener: _resource_record_post_action 02:52:38 INFO - [mozharness: 2016-09-20 09:52:38.854567Z] Finished run-tests step (success) 02:52:38 INFO - Running post-run listener: _resource_record_post_run 02:52:38 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288628736; Read time: 16; Write time: 251844 02:52:38 INFO - TinderboxPrint: CPU usage
12.9% 02:52:38 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 02:52:38 INFO - TinderboxPrint: I/O write bytes / time
288,628,736 / 251,844 02:52:38 INFO - TinderboxPrint: CPU idle
8,259.9 (87.1%) 02:52:38 INFO - TinderboxPrint: CPU user
1,179.5 (12.4%) 02:52:38 INFO - TinderboxPrint: Swap in / out
0 / 0 02:52:38 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 71249920; Read time: 0; Write time: 104120 02:52:38 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 217378816; Read time: 0; Write time: 147724 02:52:39 INFO - Running post-run listener: _upload_blobber_files 02:52:39 INFO - Blob upload gear active. 02:52:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:52:39 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 02:52:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:52:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:52:39 INFO - (blobuploader) - INFO - Open directory for files ... 02:52:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:52:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:52:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:40 INFO - (blobuploader) - INFO - Done attempting. 02:52:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:52:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:52:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:45 INFO - (blobuploader) - INFO - Done attempting. 02:52:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:52:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:52:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:52:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:52:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:52:46 INFO - (blobuploader) - INFO - Done attempting. 02:52:46 INFO - (blobuploader) - INFO - Iteration through files over. 02:52:46 INFO - Return code: 0 02:52:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:52:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:52:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7be4091503e58a6ea3795fd6f1417769129688b97dd88b812fa21713dbe6831018c01e14feeb59e8c3a491dda3553f40bbe80f96d195e16d79034ea2f43e63f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6961555f353c6fab0b53112ce2c7984edfc7b5c25f4f3577dd9f273647f14fbe1ea744716022f3381007838123b5ebe40e1392de18068f2e5dcb45208c8cb950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf18ce4885e77ef22f8ded4666869f43e0a5df5aa33a5a855e4f15a296d5865fe236ad98e8822d97f33c98a577488aa4b0700d43b8baead72291d951b36a40f6"} 02:52:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:52:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:52:46 INFO - Contents: 02:52:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7be4091503e58a6ea3795fd6f1417769129688b97dd88b812fa21713dbe6831018c01e14feeb59e8c3a491dda3553f40bbe80f96d195e16d79034ea2f43e63f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6961555f353c6fab0b53112ce2c7984edfc7b5c25f4f3577dd9f273647f14fbe1ea744716022f3381007838123b5ebe40e1392de18068f2e5dcb45208c8cb950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf18ce4885e77ef22f8ded4666869f43e0a5df5aa33a5a855e4f15a296d5865fe236ad98e8822d97f33c98a577488aa4b0700d43b8baead72291d951b36a40f6"} 02:52:46 INFO - Running post-run listener: copy_logs_to_upload_dir 02:52:46 INFO - Copying logs to upload dir... 02:52:46 INFO - mkdir: /builds/slave/test/build/upload/logs 02:52:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1676.361067 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 56 secs) (at 2016-09-20 02:52:46.280057) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 02:52:46.284113) ========= 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=cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7be4091503e58a6ea3795fd6f1417769129688b97dd88b812fa21713dbe6831018c01e14feeb59e8c3a491dda3553f40bbe80f96d195e16d79034ea2f43e63f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6961555f353c6fab0b53112ce2c7984edfc7b5c25f4f3577dd9f273647f14fbe1ea744716022f3381007838123b5ebe40e1392de18068f2e5dcb45208c8cb950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf18ce4885e77ef22f8ded4666869f43e0a5df5aa33a5a855e4f15a296d5865fe236ad98e8822d97f33c98a577488aa4b0700d43b8baead72291d951b36a40f6"} build_url:https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011229 build_url: 'https://queue.taskcluster.net/v1/task/QsL_-H9kTk6jkjCGNVQlUA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7be4091503e58a6ea3795fd6f1417769129688b97dd88b812fa21713dbe6831018c01e14feeb59e8c3a491dda3553f40bbe80f96d195e16d79034ea2f43e63f2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6961555f353c6fab0b53112ce2c7984edfc7b5c25f4f3577dd9f273647f14fbe1ea744716022f3381007838123b5ebe40e1392de18068f2e5dcb45208c8cb950", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bf18ce4885e77ef22f8ded4666869f43e0a5df5aa33a5a855e4f15a296d5865fe236ad98e8822d97f33c98a577488aa4b0700d43b8baead72291d951b36a40f6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-20 02:52:46.309624) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 02:52:46.309963) ========= 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=cbe409c6de289db6ac707de3000001d4-1474363487.465890-1053445251 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004489 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-20 02:52:46.392559) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 02:52:46.392900) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-20 02:52:46.393213) ========= ========= Total master_lag: 0.16 =========