builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1471084647.99 results: success (0) buildid: 20160813004002 builduid: 0bde007c100d40d7b490f53d036ea779 revision: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:27.990567) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:27.991128) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:27.991468) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:28.007174) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:28.007482) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-13 03:37:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2016-08-13 03:37:28 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102385 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:28.124202) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:28.124561) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022425 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:28.174576) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-13 03:37:28.174974) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 --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-aurora --rev a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 --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=7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-13 03:37:29,050 truncating revision to first 12 chars 2016-08-13 03:37:29,050 Setting DEBUG logging. 2016-08-13 03:37:29,050 attempt 1/10 2016-08-13 03:37:29,050 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a6fcbbeaddff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-13 03:37:30,119 unpacking tar archive at: mozilla-aurora-a6fcbbeaddff/testing/mozharness/ program finished with exit code 0 elapsedTime=1.447405 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-13 03:37:29.640746) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:29.641540) ========= script_repo_revision: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:29.642514) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:29.643229) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-13 03:37:29.654769) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-13 03:37:29.655099) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:37:30 INFO - MultiFileLogger online at 20160813 03:37:30 in /builds/slave/test 03:37:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:37:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:37:30 INFO - {'append_to_log': False, 03:37:30 INFO - 'base_work_dir': '/builds/slave/test', 03:37:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:37:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:37:30 INFO - 'branch': 'Mozilla-Aurora', 03:37:30 INFO - 'buildbot_json_path': 'buildprops.json', 03:37:30 INFO - 'config_files': ('talos/linux_config.py',), 03:37:30 INFO - 'default_actions': ('clobber', 03:37:30 INFO - 'read-buildbot-config', 03:37:30 INFO - 'download-and-extract', 03:37:30 INFO - 'populate-webroot', 03:37:30 INFO - 'create-virtualenv', 03:37:30 INFO - 'install', 03:37:30 INFO - 'run-tests'), 03:37:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:37:30 INFO - 'download_minidump_stackwalk': True, 03:37:30 INFO - 'download_symbols': 'ondemand', 03:37:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:37:30 INFO - 'tooltool.py': '/tools/tooltool.py', 03:37:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:37:30 INFO - '/tools/misc-python/virtualenv.py')}, 03:37:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:37:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:37:30 INFO - 'installer_path': 'installer.exe', 03:37:30 INFO - 'log_level': 'info', 03:37:30 INFO - 'log_name': 'talos', 03:37:30 INFO - 'log_to_console': True, 03:37:30 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:37:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:37:30 INFO - 'opt_config_files': (), 03:37:30 INFO - 'pip_index': False, 03:37:30 INFO - 'sps_profile': False, 03:37:30 INFO - 'sps_profile_interval': 0, 03:37:30 INFO - 'suite': 'dromaeojs', 03:37:30 INFO - 'system_bits': '32', 03:37:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:37:30 INFO - 'title': 'talos-linux64-ix-005', 03:37:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:37:30 INFO - 'use_talos_json': True, 03:37:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:37:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:37:30 INFO - 'work_dir': 'build'} 03:37:30 INFO - [mozharness: 2016-08-13 10:37:30.638154Z] Running clobber step. 03:37:30 INFO - Running pre-action listener: _resource_record_pre_action 03:37:30 INFO - Running main action method: clobber 03:37:30 INFO - rmtree: /builds/slave/test/build 03:37:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:37:31 INFO - Running post-action listener: _resource_record_post_action 03:37:31 INFO - [mozharness: 2016-08-13 10:37:31.105105Z] Finished clobber step (success) 03:37:31 INFO - [mozharness: 2016-08-13 10:37:31.105207Z] Running read-buildbot-config step. 03:37:31 INFO - Running pre-action listener: _resource_record_pre_action 03:37:31 INFO - Running main action method: read_buildbot_config 03:37:31 INFO - Using buildbot properties: 03:37:31 INFO - { 03:37:31 INFO - "project": "", 03:37:31 INFO - "product": "firefox", 03:37:31 INFO - "script_repo_revision": "production", 03:37:31 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:37:31 INFO - "repository": "", 03:37:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:37:31 INFO - "buildid": "20160813004002", 03:37:31 INFO - "stage_platform": "linux64-pgo", 03:37:31 INFO - "basedir": "/builds/slave/test", 03:37:31 INFO - "nightly_build": "True", 03:37:31 INFO - "buildnumber": 465, 03:37:31 INFO - "slavename": "talos-linux64-ix-005", 03:37:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 03:37:31 INFO - "platform": "ubuntu64_hw", 03:37:31 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:37:31 INFO - "branch": "mozilla-aurora", 03:37:31 INFO - "repo_path": "releases/mozilla-aurora", 03:37:31 INFO - "revision": "a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5", 03:37:31 INFO - "pgo_build": "True", 03:37:31 INFO - "builduid": "0bde007c100d40d7b490f53d036ea779", 03:37:31 INFO - "slavebuilddir": "test" 03:37:31 INFO - } 03:37:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:37:31 INFO - Running post-action listener: _resource_record_post_action 03:37:31 INFO - [mozharness: 2016-08-13 10:37:31.107715Z] Finished read-buildbot-config step (success) 03:37:31 INFO - [mozharness: 2016-08-13 10:37:31.107815Z] Running download-and-extract step. 03:37:31 INFO - Running pre-action listener: _resource_record_pre_action 03:37:31 INFO - Running main action method: download_and_extract 03:37:31 INFO - mkdir: /builds/slave/test/build/tests 03:37: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')]} 03:37:31 INFO - https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:37:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:37:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:37:31 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:37:31 INFO - retry: Failed, sleeping 30 seconds before retrying 03:38:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:38:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:38:01 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:38:01 INFO - retry: Failed, sleeping 60 seconds before retrying 03:39:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:39:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:39:01 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:39:01 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:39:01 INFO - Caught exception: HTTP Error 404: Not Found 03:39:01 INFO - Caught exception: HTTP Error 404: Not Found 03:39:01 INFO - Caught exception: HTTP Error 404: Not Found 03:39:01 INFO - trying https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:39:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:39:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:39:01 INFO - Downloaded 1361 bytes. 03:39:01 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:39:01 INFO - Using the following test package requirements: 03:39:01 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:39:01 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:39:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:39:01 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:39:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:39:01 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:39:01 INFO - u'jsshell-linux-x86_64.zip'], 03:39:01 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:39:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:39:01 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:39:01 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:39:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:39:01 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:39:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:39:01 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:39:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:39:01 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:39:01 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:39:01 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:39:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:39:01 INFO - https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:39:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:39:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:39:01 INFO - retry: Failed, sleeping 30 seconds before retrying 03:39:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:39:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:39:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:39:31 INFO - retry: Failed, sleeping 60 seconds before retrying 03:40:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:40:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:40:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:40:31 INFO - Caught exception: HTTP Error 404: Not Found 03:40:31 INFO - Caught exception: HTTP Error 404: Not Found 03:40:31 INFO - Caught exception: HTTP Error 404: Not Found 03:40:31 INFO - trying https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:40:33 INFO - Downloaded 21766248 bytes. 03:40:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:40:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:40:34 INFO - Return code: 0 03:40:34 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:40: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')]} 03:40:34 INFO - https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:40:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:40:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:40:34 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:40:34 INFO - retry: Failed, sleeping 30 seconds before retrying 03:41:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:41:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:41:04 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:41:04 INFO - retry: Failed, sleeping 60 seconds before retrying 03:42:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:42:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:42:04 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:42:04 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:42:04 INFO - Caught exception: HTTP Error 404: Not Found 03:42:04 INFO - Caught exception: HTTP Error 404: Not Found 03:42:04 INFO - Caught exception: HTTP Error 404: Not Found 03:42:04 INFO - trying https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:42:04 INFO - Downloading https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:42:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:42:05 INFO - Downloaded 21766248 bytes. 03:42:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:42:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:42:06 INFO - Return code: 0 03:42:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:42:06 INFO - https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:42:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:42:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:42:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:42:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:42:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:42:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:42:06 INFO - retry: Failed, sleeping 30 seconds before retrying 03:42:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:42:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:42:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:42:36 INFO - retry: Failed, sleeping 60 seconds before retrying 03:43:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:43:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:43:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:43:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:43:37 INFO - Caught exception: HTTP Error 404: Not Found 03:43:37 INFO - Caught exception: HTTP Error 404: Not Found 03:43:37 INFO - Caught exception: HTTP Error 404: Not Found 03:43:37 INFO - trying https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:43:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:43:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:43:37 INFO - Downloaded 14413723 bytes. 03:43:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:43:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:43:38 INFO - Return code: 0 03:43:38 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')]} 03:43:38 INFO - https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:43:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:43:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:43:38 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:43:38 INFO - retry: Failed, sleeping 30 seconds before retrying 03:44:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:44:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:44:08 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:44:08 INFO - retry: Failed, sleeping 60 seconds before retrying 03:45:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 03:45:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:45:08 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:45:08 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:45:08 INFO - Caught exception: HTTP Error 404: Not Found 03:45:08 INFO - Caught exception: HTTP Error 404: Not Found 03:45:08 INFO - Caught exception: HTTP Error 404: Not Found 03:45:08 INFO - trying https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:45:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:45:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:45:09 INFO - Downloaded 63751190 bytes. 03:45:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:45:09 INFO - mkdir: /builds/slave/test/properties 03:45:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:45:09 INFO - Writing to file /builds/slave/test/properties/build_url 03:45:09 INFO - Contents: 03:45:09 INFO - build_url:https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:45:10 INFO - Running post-action listener: _resource_record_post_action 03:45:10 INFO - Running post-action listener: set_extra_try_arguments 03:45:10 INFO - [mozharness: 2016-08-13 10:45:10.251245Z] Finished download-and-extract step (success) 03:45:10 INFO - [mozharness: 2016-08-13 10:45:10.251477Z] Running populate-webroot step. 03:45:10 INFO - Running pre-action listener: _resource_record_pre_action 03:45:10 INFO - Running main action method: populate_webroot 03:45:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:45:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:45:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:45:10 INFO - '--tppagecycles', 03:45:10 INFO - '7'], 03:45:10 INFO - 'tests': ['tsvgm']}}, 03:45:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:45:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:45:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:45:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:45:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:45:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:45:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:10 INFO - 'talos_options': ['--disable-e10s'], 03:45:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:45:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:45:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:10 INFO - 'talos_options': ['--disable-e10s'], 03:45:10 INFO - 'tests': ['damp', 'tps']}, 03:45:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:10 INFO - 'tests': ['damp', 'tps']}, 03:45:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:45:10 INFO - 'tests': ['dromaeo_dom']}, 03:45:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:45:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:45:10 INFO - 'tests': ['basic_compositor_video']}, 03:45:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:45:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:45:10 INFO - 'tests': ['a11yr', 03:45:10 INFO - 'ts_paint', 03:45:10 INFO - 'tpaint', 03:45:10 INFO - 'sessionrestore', 03:45:10 INFO - 'sessionrestore_no_auto_restore', 03:45:10 INFO - 'tabpaint']}, 03:45:10 INFO - 'other-e10s': {'tests': ['a11yr', 03:45:10 INFO - 'ts_paint', 03:45:10 INFO - 'tpaint', 03:45:10 INFO - 'sessionrestore', 03:45:10 INFO - 'sessionrestore_no_auto_restore', 03:45:10 INFO - 'tabpaint']}, 03:45:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:45:10 INFO - 'ts_paint', 03:45:10 INFO - 'tpaint', 03:45:10 INFO - 'sessionrestore', 03:45:10 INFO - 'sessionrestore_no_auto_restore', 03:45:10 INFO - 'tabpaint']}, 03:45:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:45:10 INFO - 'ts_paint', 03:45:10 INFO - 'tpaint', 03:45:10 INFO - 'sessionrestore', 03:45:10 INFO - 'sessionrestore_no_auto_restore', 03:45:10 INFO - 'tabpaint']}, 03:45:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:45:10 INFO - 'tests': ['a11yr', 03:45:10 INFO - 'ts_paint', 03:45:10 INFO - 'tpaint', 03:45:10 INFO - 'sessionrestore', 03:45:10 INFO - 'sessionrestore_no_auto_restore', 03:45:10 INFO - 'tabpaint']}, 03:45:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:45:10 INFO - 'tests': ['a11yr', 03:45:10 INFO - 'ts_paint', 03:45:10 INFO - 'tpaint', 03:45:10 INFO - 'sessionrestore', 03:45:10 INFO - 'sessionrestore_no_auto_restore', 03:45:10 INFO - 'tabpaint']}, 03:45:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:45:10 INFO - 'tests': ['tsvgx', 03:45:10 INFO - 'tsvgr_opacity', 03:45:10 INFO - 'tart', 03:45:10 INFO - 'tscrollx', 03:45:10 INFO - 'cart']}, 03:45:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:45:10 INFO - 'tsvgr_opacity', 03:45:10 INFO - 'tart', 03:45:10 INFO - 'tscrollx', 03:45:10 INFO - 'cart']}, 03:45:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:10 INFO - 'talos_options': ['--disable-e10s'], 03:45:10 INFO - 'tests': ['tp5o']}, 03:45:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:10 INFO - 'tests': ['tp5o']}, 03:45:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:10 INFO - 'talos_options': ['--disable-e10s', 03:45:10 INFO - '--xperf_path', 03:45:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:45:10 INFO - 'tests': ['tp5n']}, 03:45:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:10 INFO - 'talos_options': ['--xperf_path', 03:45:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:45:10 INFO - 'tests': ['tp5n']}}, 03:45:10 INFO - 'talos.zip': {'path': '', 03:45:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:45:10 INFO - Running post-action listener: _resource_record_post_action 03:45:10 INFO - [mozharness: 2016-08-13 10:45:10.270188Z] Finished populate-webroot step (success) 03:45:10 INFO - [mozharness: 2016-08-13 10:45:10.270277Z] Running create-virtualenv step. 03:45:10 INFO - Running pre-action listener: _resource_record_pre_action 03:45:10 INFO - Running main action method: create_virtualenv 03:45:10 INFO - Creating virtualenv /builds/slave/test/build/venv 03:45:10 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 03:45:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:45:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:45:10 INFO - Using real prefix '/usr' 03:45:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:45:10 INFO - Installing distribute.............................................................................................................................................................................................done. 03:45:12 INFO - Installing pip.................done. 03:45:12 INFO - Return code: 0 03:45:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:45:12 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')]} 03:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:12 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')]} 03:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:12 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 0x271e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29df180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3b0>, '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 0x29de2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', '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 03:45:12 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 03:45:12 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 03:45:12 INFO - Using env: {'DISPLAY': ':0', 03:45:12 INFO - 'HOME': '/home/cltbld', 03:45:12 INFO - 'LANG': 'en_US.UTF-8', 03:45:12 INFO - 'LANGUAGE': 'en_US:en', 03:45:12 INFO - 'LOGNAME': 'cltbld', 03:45:12 INFO - 'MAIL': '/var/mail/cltbld', 03:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:12 INFO - 'MOZ_NO_REMOTE': '1', 03:45:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:12 INFO - 'NO_EM_RESTART': '1', 03:45:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:12 INFO - 'PWD': '/builds/slave/test', 03:45:12 INFO - 'SHELL': '/bin/bash', 03:45:12 INFO - 'SHLVL': '1', 03:45:12 INFO - 'TERM': 'linux', 03:45:12 INFO - 'TMOUT': '86400', 03:45:12 INFO - 'USER': 'cltbld', 03:45:12 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', 03:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:12 INFO - '_': '/tools/buildbot/bin/python'} 03:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:13 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 03:45:13 INFO - Cleaning up... 03:45:13 INFO - Return code: 0 03:45:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:45:13 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')]} 03:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:13 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')]} 03:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:13 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 0x271e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29df180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3b0>, '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 0x29de2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', '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 03:45:13 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 03:45:13 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 03:45:13 INFO - Using env: {'DISPLAY': ':0', 03:45:13 INFO - 'HOME': '/home/cltbld', 03:45:13 INFO - 'LANG': 'en_US.UTF-8', 03:45:13 INFO - 'LANGUAGE': 'en_US:en', 03:45:13 INFO - 'LOGNAME': 'cltbld', 03:45:13 INFO - 'MAIL': '/var/mail/cltbld', 03:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:13 INFO - 'MOZ_NO_REMOTE': '1', 03:45:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:13 INFO - 'NO_EM_RESTART': '1', 03:45:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:13 INFO - 'PWD': '/builds/slave/test', 03:45:13 INFO - 'SHELL': '/bin/bash', 03:45:13 INFO - 'SHLVL': '1', 03:45:13 INFO - 'TERM': 'linux', 03:45:13 INFO - 'TMOUT': '86400', 03:45:13 INFO - 'USER': 'cltbld', 03:45:13 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', 03:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:13 INFO - '_': '/tools/buildbot/bin/python'} 03:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:13 INFO - Downloading/unpacking psutil>=3.1.1 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:13 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 03:45:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:45:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:45:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:45:15 INFO - Installing collected packages: psutil 03:45:15 INFO - Running setup.py install for psutil 03:45:15 INFO - building 'psutil._psutil_linux' extension 03:45:15 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 03:45:15 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 03:45:15 INFO - building 'psutil._psutil_posix' extension 03:45:15 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 03:45:15 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 03:45:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:45:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:45:15 INFO - Successfully installed psutil 03:45:15 INFO - Cleaning up... 03:45:15 INFO - Return code: 0 03:45:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29df180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3b0>, '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 0x29de2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', '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 03:45:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:45:15 INFO - Using env: {'DISPLAY': ':0', 03:45:15 INFO - 'HOME': '/home/cltbld', 03:45:15 INFO - 'LANG': 'en_US.UTF-8', 03:45:15 INFO - 'LANGUAGE': 'en_US:en', 03:45:15 INFO - 'LOGNAME': 'cltbld', 03:45:15 INFO - 'MAIL': '/var/mail/cltbld', 03:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:15 INFO - 'MOZ_NO_REMOTE': '1', 03:45:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:15 INFO - 'NO_EM_RESTART': '1', 03:45:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:15 INFO - 'PWD': '/builds/slave/test', 03:45:15 INFO - 'SHELL': '/bin/bash', 03:45:15 INFO - 'SHLVL': '1', 03:45:15 INFO - 'TERM': 'linux', 03:45:15 INFO - 'TMOUT': '86400', 03:45:15 INFO - 'USER': 'cltbld', 03:45:15 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', 03:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:15 INFO - '_': '/tools/buildbot/bin/python'} 03:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:45:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:45:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:45:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:45:17 INFO - Installing collected packages: mozsystemmonitor 03:45:17 INFO - Running setup.py install for mozsystemmonitor 03:45:18 INFO - Successfully installed mozsystemmonitor 03:45:18 INFO - Cleaning up... 03:45:18 INFO - Return code: 0 03:45:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:45:18 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')]} 03:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:18 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')]} 03:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:18 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 0x271e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29df180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3b0>, '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 0x29de2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', '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 03:45:18 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 03:45:18 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 03:45:18 INFO - Using env: {'DISPLAY': ':0', 03:45:18 INFO - 'HOME': '/home/cltbld', 03:45:18 INFO - 'LANG': 'en_US.UTF-8', 03:45:18 INFO - 'LANGUAGE': 'en_US:en', 03:45:18 INFO - 'LOGNAME': 'cltbld', 03:45:18 INFO - 'MAIL': '/var/mail/cltbld', 03:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:18 INFO - 'MOZ_NO_REMOTE': '1', 03:45:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:18 INFO - 'NO_EM_RESTART': '1', 03:45:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:18 INFO - 'PWD': '/builds/slave/test', 03:45:18 INFO - 'SHELL': '/bin/bash', 03:45:18 INFO - 'SHLVL': '1', 03:45:18 INFO - 'TERM': 'linux', 03:45:18 INFO - 'TMOUT': '86400', 03:45:18 INFO - 'USER': 'cltbld', 03:45:18 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', 03:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:18 INFO - '_': '/tools/buildbot/bin/python'} 03:45:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:18 INFO - Downloading/unpacking jsonschema==2.5.1 03:45:18 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 03:45:18 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 03:45:18 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 03:45:18 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 03:45:18 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 03:45:18 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 03:45:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:45:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:45:20 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 03:45:20 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 03:45:20 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 03:45:20 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 03:45:20 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 03:45:20 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 03:45:20 INFO - Downloading functools32-3.2.3-2.tar.gz 03:45:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:45:20 INFO - warning: no files found matching '*.txt' 03:45:20 INFO - no previously-included directories found matching 'build' 03:45:20 INFO - no previously-included directories found matching 'dist' 03:45:20 INFO - no previously-included directories found matching '.git*' 03:45:20 INFO - Installing collected packages: jsonschema, functools32 03:45:20 INFO - Running setup.py install for functools32 03:45:20 INFO - warning: no files found matching '*.txt' 03:45:20 INFO - no previously-included directories found matching 'build' 03:45:20 INFO - no previously-included directories found matching 'dist' 03:45:20 INFO - no previously-included directories found matching '.git*' 03:45:20 INFO - Successfully installed jsonschema functools32 03:45:20 INFO - Cleaning up... 03:45:20 INFO - Return code: 0 03:45:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:45:20 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')]} 03:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:20 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')]} 03:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:20 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 0x271e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29df180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3b0>, '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 0x29de2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', '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 03:45:20 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 03:45:20 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 03:45:20 INFO - Using env: {'DISPLAY': ':0', 03:45:20 INFO - 'HOME': '/home/cltbld', 03:45:20 INFO - 'LANG': 'en_US.UTF-8', 03:45:20 INFO - 'LANGUAGE': 'en_US:en', 03:45:20 INFO - 'LOGNAME': 'cltbld', 03:45:20 INFO - 'MAIL': '/var/mail/cltbld', 03:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:20 INFO - 'MOZ_NO_REMOTE': '1', 03:45:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:20 INFO - 'NO_EM_RESTART': '1', 03:45:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:20 INFO - 'PWD': '/builds/slave/test', 03:45:20 INFO - 'SHELL': '/bin/bash', 03:45:20 INFO - 'SHLVL': '1', 03:45:20 INFO - 'TERM': 'linux', 03:45:20 INFO - 'TMOUT': '86400', 03:45:20 INFO - 'USER': 'cltbld', 03:45:20 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', 03:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:20 INFO - '_': '/tools/buildbot/bin/python'} 03:45:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:45:20 INFO - Cleaning up... 03:45:20 INFO - Return code: 0 03:45:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:45:20 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')]} 03:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:20 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')]} 03:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:20 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 0x271e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29df180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3b0>, '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 0x29de2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', '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 03:45:20 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 03:45:20 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 03:45:20 INFO - Using env: {'DISPLAY': ':0', 03:45:20 INFO - 'HOME': '/home/cltbld', 03:45:20 INFO - 'LANG': 'en_US.UTF-8', 03:45:20 INFO - 'LANGUAGE': 'en_US:en', 03:45:20 INFO - 'LOGNAME': 'cltbld', 03:45:20 INFO - 'MAIL': '/var/mail/cltbld', 03:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:20 INFO - 'MOZ_NO_REMOTE': '1', 03:45:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:20 INFO - 'NO_EM_RESTART': '1', 03:45:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:20 INFO - 'PWD': '/builds/slave/test', 03:45:20 INFO - 'SHELL': '/bin/bash', 03:45:20 INFO - 'SHLVL': '1', 03:45:20 INFO - 'TERM': 'linux', 03:45:20 INFO - 'TMOUT': '86400', 03:45:20 INFO - 'USER': 'cltbld', 03:45:20 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', 03:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:20 INFO - '_': '/tools/buildbot/bin/python'} 03:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:21 INFO - Downloading/unpacking blobuploader==1.2.4 03:45:21 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 03:45:21 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 03:45:21 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 03:45:21 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 03:45:21 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 03:45:21 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 03:45:23 INFO - Downloading blobuploader-1.2.4.tar.gz 03:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:45:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:45:23 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 03:45:23 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 03:45:23 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 03:45:23 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 03:45:23 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 03:45:23 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 03:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:45:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:45:23 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 03:45:23 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 03:45:23 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 03:45:23 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 03:45:23 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 03:45:23 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 03:45:23 INFO - Downloading docopt-0.6.1.tar.gz 03:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:45:23 INFO - Installing collected packages: blobuploader, requests, docopt 03:45:23 INFO - Running setup.py install for blobuploader 03:45:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:45:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:45:24 INFO - Running setup.py install for requests 03:45:24 INFO - Running setup.py install for docopt 03:45:24 INFO - Successfully installed blobuploader requests docopt 03:45:24 INFO - Cleaning up... 03:45:24 INFO - Return code: 0 03:45:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:45:24 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')]} 03:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:24 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')]} 03:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:24 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 0x271e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29df180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3b0>, '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 0x29de2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', '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 03:45:24 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 03:45:24 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 03:45:24 INFO - Using env: {'DISPLAY': ':0', 03:45:24 INFO - 'HOME': '/home/cltbld', 03:45:24 INFO - 'LANG': 'en_US.UTF-8', 03:45:24 INFO - 'LANGUAGE': 'en_US:en', 03:45:24 INFO - 'LOGNAME': 'cltbld', 03:45:24 INFO - 'MAIL': '/var/mail/cltbld', 03:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:24 INFO - 'MOZ_NO_REMOTE': '1', 03:45:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:24 INFO - 'NO_EM_RESTART': '1', 03:45:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:24 INFO - 'PWD': '/builds/slave/test', 03:45:24 INFO - 'SHELL': '/bin/bash', 03:45:24 INFO - 'SHLVL': '1', 03:45:24 INFO - 'TERM': 'linux', 03:45:24 INFO - 'TMOUT': '86400', 03:45:24 INFO - 'USER': 'cltbld', 03:45:24 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', 03:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:24 INFO - '_': '/tools/buildbot/bin/python'} 03:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:45:24 INFO - Running setup.py (path:/tmp/pip-5VqVlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:45:24 INFO - Running setup.py (path:/tmp/pip-W9rKk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:45:24 INFO - Running setup.py (path:/tmp/pip-goHEAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:45:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:45:24 INFO - Running setup.py (path:/tmp/pip-h_lCnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:45:25 INFO - Running setup.py (path:/tmp/pip-yKBtA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:45:25 INFO - Running setup.py (path:/tmp/pip-H1W3Ye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:45:25 INFO - Running setup.py (path:/tmp/pip-V6LfnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:45:25 INFO - Running setup.py (path:/tmp/pip-iPAYJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:45:25 INFO - Running setup.py (path:/tmp/pip-mSHE63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:45:25 INFO - Running setup.py (path:/tmp/pip-GXtsG_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:45:25 INFO - Running setup.py (path:/tmp/pip-u4mWU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:45:25 INFO - Running setup.py (path:/tmp/pip-lVoquK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:45:25 INFO - Running setup.py (path:/tmp/pip-Kbtk1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:45:25 INFO - Running setup.py (path:/tmp/pip-ZzrSye-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:25 INFO - Running setup.py (path:/tmp/pip-2i1kmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:45:25 INFO - Running setup.py (path:/tmp/pip-H3AJnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:45:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:45:25 INFO - Running setup.py (path:/tmp/pip-9HfRPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:45:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:45:25 INFO - Running setup.py install for manifestparser 03:45:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:45:26 INFO - Running setup.py install for mozcrash 03:45:26 INFO - Running setup.py install for mozdebug 03:45:26 INFO - Running setup.py install for mozdevice 03:45:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:45:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:45:26 INFO - Running setup.py install for mozfile 03:45:26 INFO - Running setup.py install for mozhttpd 03:45:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:45:26 INFO - Running setup.py install for mozinfo 03:45:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:45:26 INFO - Running setup.py install for mozInstall 03:45:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:45:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:45:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:45:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:45:26 INFO - Running setup.py install for mozleak 03:45:26 INFO - Running setup.py install for mozlog 03:45:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:45:27 INFO - Running setup.py install for moznetwork 03:45:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:45:27 INFO - Running setup.py install for mozprocess 03:45:27 INFO - Running setup.py install for mozprofile 03:45:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:45:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:45:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:45:27 INFO - Running setup.py install for mozrunner 03:45:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:45:27 INFO - Running setup.py install for mozscreenshot 03:45:27 INFO - Running setup.py install for moztest 03:45:27 INFO - Running setup.py install for mozversion 03:45:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:45:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:45:27 INFO - Cleaning up... 03:45:27 INFO - Return code: 0 03:45:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:45:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:27 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 0x271e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29df180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3b0>, '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 0x29de2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', '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 03:45:27 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 03:45:27 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 03:45:27 INFO - Using env: {'DISPLAY': ':0', 03:45:27 INFO - 'HOME': '/home/cltbld', 03:45:27 INFO - 'LANG': 'en_US.UTF-8', 03:45:27 INFO - 'LANGUAGE': 'en_US:en', 03:45:27 INFO - 'LOGNAME': 'cltbld', 03:45:27 INFO - 'MAIL': '/var/mail/cltbld', 03:45:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:27 INFO - 'MOZ_NO_REMOTE': '1', 03:45:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:27 INFO - 'NO_EM_RESTART': '1', 03:45:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:27 INFO - 'PWD': '/builds/slave/test', 03:45:27 INFO - 'SHELL': '/bin/bash', 03:45:27 INFO - 'SHLVL': '1', 03:45:27 INFO - 'TERM': 'linux', 03:45:27 INFO - 'TMOUT': '86400', 03:45:27 INFO - 'USER': 'cltbld', 03:45:27 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', 03:45:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:27 INFO - '_': '/tools/buildbot/bin/python'} 03:45:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:45:28 INFO - Running setup.py (path:/tmp/pip-tMpXoN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:45:28 INFO - Running setup.py (path:/tmp/pip-VUc5iQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:45:28 INFO - Running setup.py (path:/tmp/pip-KTqdj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:45:28 INFO - Running setup.py (path:/tmp/pip-UGKe2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:45:28 INFO - Running setup.py (path:/tmp/pip-ta3R40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:45:28 INFO - Running setup.py (path:/tmp/pip-Opjx8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:45:28 INFO - Running setup.py (path:/tmp/pip-IkuKLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:45:28 INFO - Running setup.py (path:/tmp/pip-7MyuBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:45:28 INFO - Running setup.py (path:/tmp/pip-9ycPyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:45:28 INFO - Running setup.py (path:/tmp/pip-0M8MJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:45:28 INFO - Running setup.py (path:/tmp/pip-0eCAp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:45:28 INFO - Running setup.py (path:/tmp/pip-ZAVD7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:45:28 INFO - Running setup.py (path:/tmp/pip-TOEZn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:45:28 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)) 03:45:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:45:28 INFO - Running setup.py (path:/tmp/pip-TQ8lfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:45:29 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)) 03:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:29 INFO - Running setup.py (path:/tmp/pip-gah8_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:45:29 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)) 03:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:45:29 INFO - Running setup.py (path:/tmp/pip-7SncOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:45:29 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)) 03:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:45:29 INFO - Running setup.py (path:/tmp/pip-iTM3Ky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:45:29 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)) 03:45:29 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)) 03:45:29 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)) 03:45:29 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)) 03:45:29 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)) 03:45:29 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)) 03:45:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:45:29 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 03:45:29 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 03:45:29 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 03:45:29 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 03:45:29 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 03:45:29 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 03:45:31 INFO - Downloading blessings-1.6.tar.gz 03:45:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:45:31 INFO - Installing collected packages: blessings 03:45:31 INFO - Running setup.py install for blessings 03:45:31 INFO - Successfully installed blessings 03:45:31 INFO - Cleaning up... 03:45:31 INFO - Return code: 0 03:45:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:45:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:45:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:45:31 INFO - Reading from file tmpfile_stdout 03:45:31 INFO - Current package versions: 03:45:31 INFO - argparse == 1.2.1 03:45:31 INFO - blessings == 1.6 03:45:31 INFO - blobuploader == 1.2.4 03:45:31 INFO - docopt == 0.6.1 03:45:31 INFO - functools32 == 3.2.3-2 03:45:31 INFO - jsonschema == 2.5.1 03:45:31 INFO - manifestparser == 1.1 03:45:31 INFO - mozInstall == 1.12 03:45:31 INFO - mozcrash == 0.17 03:45:31 INFO - mozdebug == 0.1 03:45:31 INFO - mozdevice == 0.48 03:45:31 INFO - mozfile == 1.2 03:45:31 INFO - mozhttpd == 0.7 03:45:31 INFO - mozinfo == 0.9 03:45:31 INFO - mozleak == 0.1 03:45:31 INFO - mozlog == 3.3 03:45:31 INFO - moznetwork == 0.27 03:45:31 INFO - mozprocess == 0.23 03:45:31 INFO - mozprofile == 0.28 03:45:31 INFO - mozrunner == 6.12 03:45:31 INFO - mozscreenshot == 0.1 03:45:31 INFO - mozsystemmonitor == 0.3 03:45:31 INFO - moztest == 0.7 03:45:31 INFO - mozversion == 1.4 03:45:31 INFO - psutil == 3.1.1 03:45:31 INFO - requests == 1.2.3 03:45:31 INFO - wsgiref == 0.1.2 03:45:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:45: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')]} 03:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45: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')]} 03:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45:31 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 0x271e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29df180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3b0>, '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 0x29de2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', '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 03:45:31 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 03:45:31 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 03:45:31 INFO - Using env: {'DISPLAY': ':0', 03:45:31 INFO - 'HOME': '/home/cltbld', 03:45:31 INFO - 'LANG': 'en_US.UTF-8', 03:45:31 INFO - 'LANGUAGE': 'en_US:en', 03:45:31 INFO - 'LOGNAME': 'cltbld', 03:45:31 INFO - 'MAIL': '/var/mail/cltbld', 03:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:31 INFO - 'MOZ_NO_REMOTE': '1', 03:45:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:31 INFO - 'NO_EM_RESTART': '1', 03:45:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:31 INFO - 'PWD': '/builds/slave/test', 03:45:31 INFO - 'SHELL': '/bin/bash', 03:45:31 INFO - 'SHLVL': '1', 03:45:31 INFO - 'TERM': 'linux', 03:45:31 INFO - 'TMOUT': '86400', 03:45:31 INFO - 'USER': 'cltbld', 03:45:31 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', 03:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:31 INFO - '_': '/tools/buildbot/bin/python'} 03:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:31 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)) 03:45:31 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)) 03:45:31 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)) 03:45:31 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)) 03:45:31 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)) 03:45:31 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)) 03:45:31 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)) 03:45:31 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)) 03:45:31 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)) 03:45:31 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)) 03:45:31 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)) 03:45:31 INFO - Cleaning up... 03:45:31 INFO - Return code: 0 03:45:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:45: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')]} 03:45:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:45:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45: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')]} 03:45:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:45:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:45: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x271e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28818a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29df180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29dd3b0>, '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 0x29de2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', '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 03:45: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', 'jsonschema'] in /builds/slave/test/build 03:45: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 jsonschema 03:45:31 INFO - Using env: {'DISPLAY': ':0', 03:45:31 INFO - 'HOME': '/home/cltbld', 03:45:31 INFO - 'LANG': 'en_US.UTF-8', 03:45:31 INFO - 'LANGUAGE': 'en_US:en', 03:45:31 INFO - 'LOGNAME': 'cltbld', 03:45:31 INFO - 'MAIL': '/var/mail/cltbld', 03:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:31 INFO - 'MOZ_NO_REMOTE': '1', 03:45:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:31 INFO - 'NO_EM_RESTART': '1', 03:45:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:31 INFO - 'PWD': '/builds/slave/test', 03:45:31 INFO - 'SHELL': '/bin/bash', 03:45:31 INFO - 'SHLVL': '1', 03:45:31 INFO - 'TERM': 'linux', 03:45:31 INFO - 'TMOUT': '86400', 03:45:31 INFO - 'USER': 'cltbld', 03:45:31 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', 03:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:31 INFO - '_': '/tools/buildbot/bin/python'} 03:45:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:45:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:45:31 INFO - Cleaning up... 03:45:32 INFO - Return code: 0 03:45:32 INFO - Running post-action listener: _resource_record_post_action 03:45:32 INFO - Running post-action listener: _start_resource_monitoring 03:45:32 INFO - Starting resource monitoring. 03:45:32 INFO - [mozharness: 2016-08-13 10:45:32.033341Z] Finished create-virtualenv step (success) 03:45:32 INFO - [mozharness: 2016-08-13 10:45:32.033813Z] Running install step. 03:45:32 INFO - Running pre-action listener: _resource_record_pre_action 03:45:32 INFO - Running main action method: install 03:45:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:45:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:45:32 INFO - Reading from file tmpfile_stdout 03:45:32 INFO - Detecting whether we're running mozinstall >=1.0... 03:45:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:45:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:45:32 INFO - Reading from file tmpfile_stdout 03:45:32 INFO - Output received: 03:45:32 INFO - Usage: mozinstall [options] installer 03:45:32 INFO - Options: 03:45:32 INFO - -h, --help show this help message and exit 03:45:32 INFO - -d DEST, --destination=DEST 03:45:32 INFO - Directory to install application into. [default: 03:45:32 INFO - "/builds/slave/test"] 03:45:32 INFO - --app=APP Application being installed. [default: firefox] 03:45:32 INFO - mkdir: /builds/slave/test/build/application 03:45:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:45:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:45:48 INFO - Reading from file tmpfile_stdout 03:45:48 INFO - Output received: 03:45:48 INFO - /builds/slave/test/build/application/firefox/firefox 03:45:48 INFO - Running post-action listener: _resource_record_post_action 03:45:48 INFO - [mozharness: 2016-08-13 10:45:48.645870Z] Finished install step (success) 03:45:48 INFO - [mozharness: 2016-08-13 10:45:48.646148Z] Running run-tests step. 03:45:48 INFO - Running pre-action listener: _resource_record_pre_action 03:45:48 INFO - Running main action method: run_tests 03:45:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:45:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:45:48 INFO - Python 2.7.3 03:45:48 INFO - Return code: 0 03:45:48 INFO - grabbing minidump binary from tooltool 03:45:48 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')]} 03:45:48 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 0x29dd3b0>, '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 0x29de2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29de740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:45:48 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 03:45:48 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 03:45:48 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 03:45:48 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:45:48 INFO - Return code: 0 03:45:48 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:45:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:45:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:45:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:45:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:45:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 03:45:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 03:45:48 INFO - Using env: {'DISPLAY': ':0', 03:45:48 INFO - 'HOME': '/home/cltbld', 03:45:48 INFO - 'LANG': 'en_US.UTF-8', 03:45:48 INFO - 'LANGUAGE': 'en_US:en', 03:45:48 INFO - 'LOGNAME': 'cltbld', 03:45:48 INFO - 'MAIL': '/var/mail/cltbld', 03:45:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:45:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:45:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:45:48 INFO - 'MOZ_NO_REMOTE': '1', 03:45:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:45:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:45:48 INFO - 'NO_EM_RESTART': '1', 03:45:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:45:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:45:48 INFO - 'PWD': '/builds/slave/test', 03:45:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:45:48 INFO - 'SHELL': '/bin/bash', 03:45:48 INFO - 'SHLVL': '1', 03:45:48 INFO - 'TERM': 'linux', 03:45:48 INFO - 'TMOUT': '86400', 03:45:48 INFO - 'USER': 'cltbld', 03:45:48 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562', 03:45:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:45:48 INFO - '_': '/tools/buildbot/bin/python'} 03:45:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 03:45:48 INFO - mozversion application_buildid: 20160813004002 03:45:48 INFO - mozversion application_changeset: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 03:45:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:45:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:45:48 INFO - mozversion application_name: Firefox 03:45:48 INFO - mozversion application_remotingname: firefox-dev 03:45:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:45:48 INFO - mozversion application_vendor: Mozilla 03:45:48 INFO - mozversion application_version: 50.0a2 03:45:48 INFO - mozversion platform_buildid: 20160813004002 03:45:48 INFO - mozversion platform_changeset: a6fcbbeaddff2a06a9350f2264f0330fc2c1a0b5 03:45:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:45:48 INFO - mozversion platform_version: 50.0a2 03:45:48 INFO - using testdate: 1471085148 03:45:48 INFO - actual date: 1471085148 03:45:48 INFO - starting webserver on 'localhost:48506' 03:45:48 INFO - SUITE-START | Running 2 tests 03:45:48 INFO - TEST-START | dromaeo_css 03:45:48 INFO - operating with platform_type : linux_ 03:45:48 INFO - Initialising browser for dromaeo_css test... 03:45:48 INFO - TEST-INFO | started process 19508 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjP3S1h/profile http://localhost:48506/getInfo.html) 03:45:52 INFO - PROCESS | 19508 | __metrics Screen width/height:1600/1200 03:45:52 INFO - PROCESS | 19508 | colorDepth:24 03:45:52 INFO - PROCESS | 19508 | Browser inner width/height: 1024/697 03:45:52 INFO - PROCESS | 19508 | __metrics 03:45:53 INFO - PROCESS | 19508 | 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" 03:45:53 INFO - PROCESS | 19508 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:45:54 INFO - TEST-INFO | 19508: exit 0 03:45:54 INFO - Browser initialized. 03:45:54 INFO - Running cycle 1/1 for dromaeo_css test... 03:45:54 INFO - TEST-INFO | started process 19599 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjP3S1h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:46:06 INFO - PROCESS | 19599 | RSS: Main: 144904192 03:46:06 INFO - PROCESS | 19599 | 03:46:57 INFO - PROCESS | 19599 | 1471085217357 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] 03:49:06 INFO - PROCESS | 19599 | Cycle 1(1): loaded http://localhost:48506/tests/dromaeo/cssquery-dojo.html (next: http://localhost:48506/tests/dromaeo/cssquery-ext.html) 03:49:07 INFO - PROCESS | 19599 | RSS: Main: 220147712 03:49:07 INFO - PROCESS | 19599 | 03:52:10 INFO - PROCESS | 19599 | Cycle 1(1): loaded http://localhost:48506/tests/dromaeo/cssquery-ext.html (next: http://localhost:48506/tests/dromaeo/cssquery-jquery.html) 03:52:10 INFO - PROCESS | 19599 | RSS: Main: 229695488 03:52:10 INFO - PROCESS | 19599 | 03:55:12 INFO - PROCESS | 19599 | Cycle 1(1): loaded http://localhost:48506/tests/dromaeo/cssquery-jquery.html (next: http://localhost:48506/tests/dromaeo/cssquery-mootools.html) 03:55:13 INFO - PROCESS | 19599 | RSS: Main: 175919104 03:55:13 INFO - PROCESS | 19599 | 03:58:15 INFO - PROCESS | 19599 | Cycle 1(1): loaded http://localhost:48506/tests/dromaeo/cssquery-mootools.html (next: http://localhost:48506/tests/dromaeo/cssquery-prototype.html) 03:58:15 INFO - PROCESS | 19599 | RSS: Main: 173957120 03:58:15 INFO - PROCESS | 19599 | 04:01:18 INFO - PROCESS | 19599 | Cycle 1(1): loaded http://localhost:48506/tests/dromaeo/cssquery-prototype.html (next: http://localhost:48506/tests/dromaeo/cssquery-yui.html) 04:01:19 INFO - PROCESS | 19599 | RSS: Main: 175706112 04:01:19 INFO - PROCESS | 19599 | 04:04:21 INFO - PROCESS | 19599 | Cycle 1(1): loaded http://localhost:48506/tests/dromaeo/cssquery-yui.html (next: http://localhost:48506/tests/dromaeo/cssquery-dojo.html) 04:04:21 INFO - PROCESS | 19599 | RSS: Main: 268627968 04:04:21 INFO - PROCESS | 19599 | 04:04:21 INFO - PROCESS | 19599 | __start_tp_report 04:04:21 INFO - PROCESS | 19599 | _x_x_mozilla_page_load 04:04:21 INFO - PROCESS | 19599 | _x_x_mozilla_page_load_details 04:04:21 INFO - PROCESS | 19599 | |i|pagename|runs| 04:04:21 INFO - PROCESS | 19599 | |0;dojo.html;3257.2019855135495;3293.4895091260846;3304.9919774468794;3331.616864060634;3342.3315336932606;5579.7489112989915;5642.915356269659;5683.658980461175;5708.1437784332375;5709.1436284557285;5631.662100273987;5643.294588176478;5714.1428785682165;5719.942800572004;5721.885562288751;4973.776180071905;5064.493550644943;5159;5171.198464238049;5216.7391630418515;5147;5197.766100525486;5208.973955130219;5209.817656382027;5213.113770658997;5099.235114732783;5120.795168193268;5133.871653208662;5139.331886854722;5190.506901844319;5218.452062533436;5225.843224703265;5230.08473517133;5243.108671525831;5272.815451459219;4981.178105612569;5010.298558201855;5031.899362012776;5137.691738495702;5144.254083157921;5502.064649009678;5505.229267902457;5525.475079867447;5539.778408863641;5581.358143813472;6012.398760123996;6023.939760602381;6038.2150320458495;6043.576949613529;6060.030395136757;5903.232579764646;5915.467607915309;6007.429294217042;6013.36859629737;6077.4226448487325;5642.746076426571;5662.943370566241;5663.716814159276;5668.093105103205;5668.376478587352;6145.723442445101;6146.0473626587955;6152.692365381713;6157.568970172045;6157.753689852445;5822.33043200029;5865.530757539386;5917.585768996215;5929.644221346733;6001.279846418458;6025.246844144482;6030.487408570233;6060.909086363708;6080.0879868019265;6086.9391306087255;6019.367966363557;6024.668643224665;6028.246469191351;6031.788887388921;6031.8793624127275;5844.211031510777;5904.3210030847;5911.704414779244;5942.108683697394;6025;4569.702969306984;4570.54294570547;4578.061497393093;4579.839705610288;4579.862604121881;4115.855945041909;4128.649064829463;4130.070734084867;4136.462259906193;4157.729747566459;5578.107502799614;5600.047991841397;5621.7189140542805;5653.830385088454;5728.169415434739;6046.848828779316;6067.1202675611785;6068.575199736064;6068.908966365508;6070.271567411851;5747.971260143756;5753.597248192586;5757.510611598061;5759.136129580596;5760;5097.413797413271;5174.275601415805;5219.765110570033;5223.817166399158;5273.209018647157;6271.560990730696;6276.968615156895;6285.874282514233;6290.24517057956;6292.905606415908;5985.132155837467;6023.036314189709;6028.3971602839365;6035.185249991195;6049.122877182695;6128.601640893327;6203.596766210362;6211.09939058843;6264.154339164154;6304.968475157595;5953.245100281329;5964.940350596612;5968.940310596839;5978.043513038067;6006.489448396936;6072.787452439144;6089.2997305310455;6090.116933044772;6092.908606370819;6099.512039036777;5760.366359700513;5832.708364581669;5837.620554663933;5861.32594751609;5932.79235226765;5873.765049397976;5880.911786323294;5883.94116058834;5887.735051922759;5916.526677865846;5806.2451881255165;5822.068469045102;5855.9121613176685;5864.266966629171;5872.471477567039;5790.855228619318;5847.561432892465;5863.794767183128;5895.351511333835;5973.104034394875;5765.4811067004175;5766.884662306814;5768.077107662753;5772.134179873053;5772.855678608068 04:04:21 INFO - PROCESS | 19599 | |1;ext.html;609.7164818359462;634.3054355480748;650.4893658478093;661.2131563439508;671.090672139251;35770.64229357709;47213.763931180336;48052.51947480524;48115.27827082597;48217;49551;49897;49985.75007124959;49995.250071248964;50047;8678.913210867873;9321.254299656011;9380.718578442658;9452.10205030521;9464.432134071978;10949.178811589123;11205;11230.775384492346;11520.42397880107;11547.076233901267;11411.657650270505;11501.252418592807;11514.366709830956;11516.251443656178;11518.596849110283;11301.943490282538;11341.603043893425;11357.034652054585;11360.261582997078;11365.943170284178;11183.161262905232;11237.943810280978;11247.887521124765;11251.662450126427;11255.155863310285;45441.31838022432;45554.316685249745;45736;45750.313745293846;45774.08451830978;3666.2850744104644;3967.960320396788;4015.5382131054716;4217.430646862665;4359.10638319147;33767.662323376695;34737.652623473696;34814.47778283328;35208.64791352105;36994.8150259247;4209.5579964103945;4284.100338928829;4289.099289149282;4289.442372491588;4338.3058710606465;381776;381888;382957;383277.9999999972;384328;65247.67376163089;65634;65649.67175164189;65729;65769.67115164394;40437.59562404397;41202.38196427056;41294.1741165181;42728.78635606802;43164;19728.210871565265;19895.60208795816;19923.302684405975;19931.10310036052;19941;13365.198088114745;14577.344019519147;14626.926865365605;14669.853301466848;15006.62483437901;21135;21346.252881149398;21457.570848582942;21463.141474341162;21468.355949321547;7573.841455185246;7627.618619069135;7681.500702454323;7707.537547747153;7733.961330193426;1050.7005503431485;1092.306385445232;1114.3258328711013;1184.3130984029242;1214.2532342609231;34369.82815085909;34949.30101397958;35071.4739278911;35182.472262916075;35188.64811351905;1135.5117299561268;1139.9544018239178;1153.348358177627;1158.976820463586;1216.8478940132484;17328.653426931643;18142.274309027493;18365.265389384276;18434.446966591022;18541.35105271336;583917;589642;590268;591498;592941;64046.35953640497;64630.353696463375;64780;64811.67594161999;64870.67564662241;1355.8372995240634;1419.0824262824049;1452.3464441001381;1497.3037537544876;1499.3777582303221;33586.49620255649;35912.4613130798;36124;36297.45553816642;36391.27217455583;55537.444625553224;56272.71863640656;56273.718631406584;56297.71851140718;56367.71816140893;421140;421402;421847;424905;426163;30815.53776693396;31928.52107218347;32154.356912861145;33409.665903340654;33416.832915835264;53894;54031;54123;54231;54297.45702542924;43522.84663211884;44601.33098003597;44794.77602611966;45659;46678.53321466742;45649.31526027046;45788.31317530306;46269.768651156526;46373.30440043334;47316.2902556455;44878.326825097;45600;45904.540954591364;46300.30549541692;46322.53677463182;38332.80833595926;39419.80290098531;39739.40390894081;40579;42952.78523607362;29219.85390073036;29234.322647157987;29573.704262957956;29683;29766.702332976394 04:04:21 INFO - PROCESS | 19599 | |2;jquery.html;396.70247314514114;402.06920977936096;423.0966885699033;423.7224617875292;427.568156162276;17528;20598.279060232893;20747.688784668222;20955.47611309716;21256.8937155314;19805.306814261505;20174.596508069866;20601.587968240627;20714;20973.265935692252;5610.943890561093;5921.970390148043;5964.493018093467;5970.79102231422;5973.432523910222;4323;5909.911351329712;5985.431384018533;6420.614763114205;6694.631795251259;6697.229818570853;6745.392914637681;6751.25736169021;6752.561083529579;6757.358050985174;6488.643124628144;6683;6687.264400915896;6718.496112791511;6727.4618030557685;5793.275840519935;5963.13534537494;5965.4929331006915;5997.820065398045;6016.75932962681;25267.241982740365;25374;25406.36484087894;25525;25555.105571305103;2557.539642864284;2622.93442663933;2625.829321094123;2656.2296933889147;3070.6775788542104;14539.200343981076;15168.24158792056;15711.135887526181;15729.449469268635;16044.75932861008;2008.337248707923;2085.7914208579023;2096.1929657081982;2129.9254526091668;2198.219632030638;84184.57907710422;92212.53893730488;92736.07263927342;93233.06766932312;93239.53380233055;11398.430078496207;11545.74857902701;11746.882531174579;11951.521939122511;12011.33937633421;17071.317147314217;17124.914375428292;17154.313827446764;17178.828211717973;17198.226079826436;8898.644054237759;8910.153535414125;9060;9101.635934562544;9138.954305228564;8711.695090671796;8722.825543489094;8751.693690720795;8937.508437035895;8962.551872406353;5144.408393034774;5156.303898973591;5173.560247379015;5197.97401013;5292.179712144623;1985.9106340214726;2017.7074324222906;2082.666773316262;2105.1368938735045;2106.325975687765;948.5209968965628;1006.9395836249702;1033.2199189611795;1053.4837929414532;1065.3181963543338;20815.37553873386;21257.468563286042;21266.680999785014;21363.893180533996;21600.351989440343;1025.779457416659;1030.9278350515542;1057.1278695076592;1070.903618674323;1145.9255148415327;11309.886901131047;11867.465964031639;11925.642230733092;11962.641120766391;12009.039276857831;457679;458396;461967;558343;570415;57353.71323143441;72457;102971.4851425738;105424;109226.45386773015;1040.2977989856815;1053.2469284461647;1073.8013467508536;1080.783843231357;1086.7500474890662;19278.710819338;19323;19596.510087247934;19630.312939047064;19695.507612309808;44881.55118448774;45771.542284576724;46371.161734144276;46764;46780.766096170664;57775;63861.3613863874;63921.3607863934;65121.67439162774;65238.673806630664;8419.958939213158;8547.786305342415;8682.479051256945;8772.605232764668;8833.823323533365;10821.567137314734;11831.40842957866;11897.82153267719;11996;12094.758104837678;10467.319624224401;10526.368417894952;10535.736606584896;10656.200784941006;10965.122790176927;10428;10490.31812932157;10808.675739727507;10891.782164356828;10925.562977480811;9796.85304720444;10696.304740191863;10867;10986.340819550853;11252.606158784403;8165.142660020612;8570.48577085377;8572.699955501528;8579.485230886166;8593.44142630727;8847.69033083839;9564.904350956402;9639.325247232626;10997.395143267197;11086.168537359568 04:04:21 INFO - PROCESS | 19599 | |3;mootools.html;1409.3094383751964;1445.1979151570881;1516.5753588995083;1665.9167041647916;1850.935217267395;8399.076101628823;8919.598618062195;9257.861132083024;9261.166495015448;9509.80980380392;9310.767230819232;9358.110979456958;9419;9426.34015618907;9427.95286023569;2490.091116742385;2499.5000999800022;2516.1319344826006;2516.2954372775653;2522;2416.1422694943217;2477.55404027275;2486.4032632168332;2487.7263501014872;2489.26566662834;2429.7691719286727;2445.877706114696;2490.9252722418264;2498.600223964166;2499.487605040962;2355.6819829322994;2377.821663375245;2380.036085385413;2459.3728599207266;2460.5817011108156;2380.024190082075;2382.201962342617;2408.843425177358;2412.6863507744056;2417.8791060447084;9374.85937710935;9381;9700.27247956406;9816.607335706563;9818.26363022776;5625.54995600351;5836.9124463133085;5847.210626565402;5849.678267695275;5854.560907931879;3615.6745892869503;3747.381682022463;3775.716821238391;3829.0235989822695;3834.0989867381145;1159.0354349377808;1159.9560395643903;1169.7075731067234;1198.1373411143963;1201.285235285004;14095.718085638333;14182.29088545579;14228.857711422961;14239.430422783076;14241;4743.146233677971;4780.569748722632;4793.257045158006;4885.9755701221975;4990.451050384455;10514.158867290598;10516.842247366296;10518.579256829642;10526.157907367393;10527.84208236877;5592.328920529562;5605.607607467438;5610.158476228598;5610.298712660918;5610.663360198401;4717.127331443694;4760;5122.667026643257;5178.844634660966;5200.2979597754575;4162.375643653476;4169.76257842293;4213.094184750232;4221.936670949938;4295.935560966588;1234.4630085912656;1239.4980033086615;1254.1910467748435;1261.4512686981193;1285.7235694325766;1073.9087177589993;1096.7203363142348;1103.5530610102921;1106.6956586938657;1118.1334465789078;6533.41199292066;6849.623270720059;6865.0732151159955;6909.447244220451;6942.757003504954;452.3937923182885;455.59224494077614;455.67419295204087;463.59203900567286;471.5284715284715;4376.124775045004;4381.9123617527475;4421.292593185075;4449.377087207794;4481.282994720829;12077.335746534025;12439.129260951828;12534.435950382254;12548.498060077494;12555.497780088877;11635.185537012328;12381.690457738448;12383.504659813687;12414.689632759073;12415.689607759878;8729.388942773945;9178.173964343241;9187.356885017985;9190;9191.89697236336;6714.261431242657;6768;6867.965660171667;6915.930840691529;7006.929930700831;10966.616168434066;11368.317900925971;11433.542658293574;11445.656630300771;11540.192186546861;9592.472414017297;10490.527926243185;11745.236559623598;11760.411979400824;11890.048796096118;8570.314374849871;8987.056359082464;8991.865122023044;9001.144891235323;9018.639254429749;9192.586333615138;9270.860937085814;9350.906490935004;9571.712848614552;9676.225901927688;8401.201885820838;8496.830063398822;8709.303255739653;8796.07641197665;8990.190882820578;8247.134050924567;8268.545230012167;8268.875966860382;8593.140685931357;8616.956915215384;8064.677412903418;8178;8184.918150818416;8190.058143313595;8198.508089514648;5692.203091567267;5765.192872997867;5823.621464604787;5852.882942341214;5900.409959004237;5889.911651325147;5903.881922361615;5953;5990.191324171181;6032.4570788629235 04:04:21 INFO - PROCESS | 19599 | |4;prototype.html;353.15948043656095;355.8558783692604;358.7112374538496;361.82270687363183;363.7999100494727;5495.230667706524;5523.7238138093035;5545.694986775726;5557.72211389431;5568.303962004749;5479.397266300703;5511.559075273978;5521.426393477859;5541.972290138554;5553.7778488860395;4029.294873397144;4078.816453259603;4275.679324050693;4294.828206871722;4297.355396690506;4314.37441570972;4337.175936572041;4346.739195648255;4367.08291258836;4367.497737760151;4273.337632666926;4283.121959998209;4285.485741710999;4291.97854010731;4342.3052311630145;4097.549269580344;4126.524085548677;4254.936175957363;4302.548232435581;4323.870283891489;3818.4344418788164;3829.2915810574855;3839.9232015359817;3844.826982785781;3855.20968201518;5302.602304827154;5304.708241046741;5312.548433383167;5323.653962492425;5332.573394128499;4801.255805350176;4833.879153021167;4896.387951506062;4910.950890491085;4920.532549407801;5619.494245517923;5621.971890140523;5628.887422251573;5632.71836408182;5636.239107720447;4233.174530966463;4233.322668373076;4246;4269.935950960738;4286.39990401344;5710.2862142232225;5848.327442344143;5875.941240587582;5881.3530511643685;5942.7920022799;4347.108842687241;4351.782410879507;4363.127374525107;4363.2800587903275;4378.277584198571;4420.447444069491;4422.690411671152;4425.6238219751685;4427.889302767392;4436.778161091933;4802.399700037496;4869.975650121798;4883.438404583519;4920.72935988524;4948.911239527299;5612.045952188138;5617.38208797032;5618.2415373924;5634.605577609528;5680.829575112753;1498.625343664084;1503.3159912239905;1522.7868098466224;1523.5581681312294;1532.387045181914;974.2985050763439;978.7846673731767;986.3046552180696;993.8260804359209;1009.2027298434301;5446.673199608036;5509.429812503417;5571.526420254243;5606.747696353674;5613.270274864241;5870.3249126350165;5888.205092312483;5889.793857215062;5899.410058994066;5910.527157827363;5306.62853600252;5574.804881829116;5575.749091290902;5582.190582365615;5595.916061259084;3079;3103.115612050555;3105.9223519411744;3112.1130477813713;3116.0213264577155;5845.444682755132;6091.208142941389;6098.32918378978;6103.42017508336;6113.786017489456;5890.293164820163;5941.762329506858;5941.762329506858;5952.345242023374;5965.582409231399;6100.468305653193;6102.9694851525455;6104.17593624855;6129.693515324216;6140.2017737694705;5685.374608792947;5821.224526519452;5846.538365408648;5851.151583020353;5864.179014937998;5764.962306784819;5785.595008349544;5787.334456537552;5802.4197580241635;5804.709764511842;6001.699915004145;6047.969760151347;6073;6135.141080248858;6151.569390142647;4250.12872361159;4281.700280980302;4282.700210985326;4293.613574778285;4296.656267498655;5880.529557635294;5897.196308381156;5903.940960590339;5905.852353691193;5915.290165180206;5539.556835453234;5682.3237145041485;5734.569907257056;5783.16144159103;5787.94212057874;5723.313202415737;5753.453421924911;5756.56825738063;5769.826905192851;5779.624324418899;5419.674819510684;5669.262995810518;5712;5712.143178523254;5732.455416735404;5182.352205974254;5724.227229323988;5728.914066288926;5745.425457454388;5748.166515855261;5592;5608.158776183605;5617.612514681163;5628;5646.830595082154 04:04:21 INFO - PROCESS | 19599 | |5;yui.html;807.838432313537;823.493551465848;849.5794581682067;851.5359129274544;859.5100792548247;14903.776443353345;15758.527244182678;15775.290111944962;15776.605584860385;15791.842081579181;15283.159426231552;15518.68962620747;15556.377744890218;15562.144082075481;15582;711.0507472524187;843.1863251961855;849.995750021249;852.4842470305468;880.5201165364891;879.226280872831;880.6609455359669;885.2254277507182;886.0696268917643;886.8226354729047;922.7324076017946;923.201430762389;925.181214625055;926.0276709455045;926.8378033844084;761.1285078585017;894.7047474333455;917.4907926101012;919.4253591505309;927.8237134944407;678.098129487785;678.725116327888;678.8268991407209;678.8472593666435;678.9592624442549;13397.263150526716;13409.66475838102;13498.190108593417;13509;13541.729165416637;15172.317245723969;15371.846281537153;15470.381184752596;15502.922485387613;15692.529224123295;14239.43042278318;14266.500672476413;14366.425342986267;14393.496227631982;14399.568012959628;4551.385562949025;4615.423072115986;4639.443266807971;4688.062387522509;4722.858314250578;84508.15491845064;85858;88681;88733;89286.55356723304;12035.759284814254;12503.249805011728;12638.873611263769;12657.177283476545;12794.040446966515;23546;23576.528469430858;23643.290701278987;23735.525289494115;23766.405839853796;8596.140385961355;8840.778980525409;8842.115788421106;8868.33487488441;9294.502877555044;4706.599939005155;4815.15734746418;4872.731999740048;4958.404991401055;5061.746912654353;3750.381187104097;3884.378499440076;3892.2020985697864;3892.4355968384425;3893.9415908761393;598.1565991951328;622.3807311724863;632.6330728177646;645.4642646603309;658.5850913924197;631.36232405271;665.6405541007898;669.4677731203705;725.5066554742825;768.5196752029981;10156.746081348025;10737.194710396596;10821.783564328669;10882.945585272022;10977.12183025372;659.1595715462823;693.0955103589864;708.0759608710682;739.9630018499054;790.7509134622576;4802.519748025239;4994.5754610858485;5047.293378926953;5055.949440505621;5057.772400241998;74082;118078.8192118085;118787.40606296914;130302;131912.3404382972;111786.44106779576;112255.43872280586;112821.43589282165;113342;117682.99999999828;63441;99963.50018249862;100840.49579752202;101347.49326253468;102059.97940020653;9713.854292185482;9761.219102471643;9840.409575425498;9846.753831154278;9967;58319;58981;59145;59853.700731496065;60698.69650651718;79913.60043199747;85294.57352713197;85371.57314213389;87287;88988.5550572243;12751.936240318739;12869.227846328875;12873.871261287268;12876.163049401759;12993.136585438553;14130.434782608992;14165.858341416453;14270.286485675882;14383.640408989859;14653.780193297322;13041.478340866259;13284.535041273895;13338.666533336744;13758.243296618783;13908.51320203788;13487.393067311797;13637.18176909349;13907.791383129059;14215.857841421453;14499.13005219648;12941.676458088246;13147;13299;13347.799783003069;13947.930260348634;10430.426326552113;10564;10706.571737130427;11010;11265.324080555192;10124.291299608956;11136.164787640797;11217.60738374153;11543.249688770204;11624.709382265512 04:04:21 INFO - PROCESS | 19599 | __end_tp_report 04:04:21 INFO - PROCESS | 19599 | __start_cc_report 04:04:21 INFO - PROCESS | 19599 | _x_x_mozilla_cycle_collect,1059 04:04:21 INFO - PROCESS | 19599 | __end_cc_report 04:04:21 INFO - PROCESS | 19599 | __startTimestamp1471086261733__endTimestamp 04:04:21 INFO - PROCESS | 19599 | 04:04:21 INFO - PROCESS | 19599 | ------- Summary: start ------- 04:04:21 INFO - PROCESS | 19599 | Number of tests: 6 04:04:21 INFO - PROCESS | 19599 | 04:04:21 INFO - PROCESS | 19599 | [#0] dojo.html Cycles:165 Average:5601.89 Median:5806.25 stddev:627.67 (10.8%) stddev-sans-first:602.04 04:04:21 INFO - PROCESS | 19599 | Values: 3257.2 3293.5 3305.0 3331.6 3342.3 5579.7 5642.9 5683.7 5708.1 5709.1 5631.7 5643.3 5714.1 5719.9 5721.9 4973.8 5064.5 5159.0 5171.2 5216.7 5147.0 5197.8 5209.0 5209.8 5213.1 5099.2 5120.8 5133.9 5139.3 5190.5 5218.5 5225.8 5230.1 5243.1 5272.8 4981.2 5010.3 5031.9 5137.7 5144.3 5502.1 5505.2 5525.5 5539.8 5581.4 6012.4 6023.9 6038.2 6043.6 6060.0 5903.2 5915.5 6007.4 6013.4 6077.4 5642.7 5662.9 5663.7 5668.1 5668.4 6145.7 6146.0 6152.7 6157.6 6157.8 5822.3 5865.5 5917.6 5929.6 6001.3 6025.2 6030.5 6060.9 6080.1 6086.9 6019.4 6024.7 6028.2 6031.8 6031.9 5844.2 5904.3 5911.7 5942.1 6025.0 4569.7 4570.5 4578.1 4579.8 4579.9 4115.9 4128.6 4130.1 4136.5 4157.7 5578.1 5600.0 5621.7 5653.8 5728.2 6046.8 6067.1 6068.6 6068.9 6070.3 5748.0 5753.6 5757.5 5759.1 5760.0 5097.4 5174.3 5219.8 5223.8 5273.2 6271.6 6277.0 6285.9 6290.2 6292.9 5985.1 6023.0 6028.4 6035.2 6049.1 6128.6 6203.6 6211.1 6264.2 6305.0 5953.2 5964.9 5968.9 5978.0 6006.5 6072.8 6089.3 6090.1 6092.9 6099.5 5760.4 5832.7 5837.6 5861.3 5932.8 5873.8 5880.9 5883.9 5887.7 5916.5 5806.2 5822.1 5855.9 5864.3 5872.5 5790.9 5847.6 5863.8 5895.4 5973.1 5765.5 5766.9 5768.1 5772.1 5772.9 04:04:21 INFO - PROCESS | 19599 | 04:04:21 INFO - PROCESS | 19599 | [#1] ext.html Cycles:180 Average:64239.94 Median:21877.47 stddev:125439.68 (573.4%) stddev-sans-first:125700.60 04:04:21 INFO - PROCESS | 19599 | Values: 609.7 634.3 650.5 661.2 671.1 35770.6 47213.8 48052.5 48115.3 48217.0 49551.0 49897.0 49985.8 49995.3 50047.0 8678.9 9321.3 9380.7 9452.1 9464.4 10949.2 11205.0 11230.8 11520.4 11547.1 11411.7 11501.3 11514.4 11516.3 11518.6 11301.9 11341.6 11357.0 11360.3 11365.9 11183.2 11237.9 11247.9 11251.7 11255.2 45441.3 45554.3 45736.0 45750.3 45774.1 3666.3 3968.0 4015.5 4217.4 4359.1 33767.7 34737.7 34814.5 35208.6 36994.8 4209.6 4284.1 4289.1 4289.4 4338.3 381776.0 381888.0 382957.0 383278.0 384328.0 65247.7 65634.0 65649.7 65729.0 65769.7 40437.6 41202.4 41294.2 42728.8 43164.0 19728.2 19895.6 19923.3 19931.1 19941.0 13365.2 14577.3 14626.9 14669.9 15006.6 21135.0 21346.3 21457.6 21463.1 21468.4 7573.8 7627.6 7681.5 7707.5 7734.0 1050.7 1092.3 1114.3 1184.3 1214.3 34369.8 34949.3 35071.5 35182.5 35188.6 1135.5 1140.0 1153.3 1159.0 1216.8 17328.7 18142.3 18365.3 18434.4 18541.4 583917.0 589642.0 590268.0 591498.0 592941.0 64046.4 64630.4 64780.0 64811.7 64870.7 1355.8 1419.1 1452.3 1497.3 1499.4 33586.5 35912.5 36124.0 36297.5 36391.3 55537.4 56272.7 56273.7 56297.7 56367.7 421140.0 421402.0 421847.0 424905.0 426163.0 30815.5 31928.5 32154.4 33409.7 33416.8 53894.0 54031.0 54123.0 54231.0 54297.5 43522.8 44601.3 44794.8 45659.0 46678.5 45649.3 45788.3 46269.8 46373.3 47316.3 44878.3 45600.0 45904.5 46300.3 46322.5 38332.8 39419.8 39739.4 40579.0 42952.8 29219.9 29234.3 29573.7 29683.0 29766.7 04:04:21 INFO - PROCESS | 19599 | 04:04:21 INFO - PROCESS | 19599 | [#2] jquery.html Cycles:180 Average:30272.06 Median:25465.68 stddev:83258.21 (326.9%) stddev-sans-first:83461.55 04:04:21 INFO - PROCESS | 19599 | Values: 396.7 402.1 423.1 423.7 427.6 17528.0 20598.3 20747.7 20955.5 21256.9 19805.3 20174.6 20601.6 20714.0 20973.3 5610.9 5922.0 5964.5 5970.8 5973.4 4323.0 5909.9 5985.4 6420.6 6694.6 6697.2 6745.4 6751.3 6752.6 6757.4 6488.6 6683.0 6687.3 6718.5 6727.5 5793.3 5963.1 5965.5 5997.8 6016.8 25267.2 25374.0 25406.4 25525.0 25555.1 2557.5 2622.9 2625.8 2656.2 3070.7 14539.2 15168.2 15711.1 15729.4 16044.8 2008.3 2085.8 2096.2 2129.9 2198.2 84184.6 92212.5 92736.1 93233.1 93239.5 11398.4 11545.7 11746.9 11951.5 12011.3 17071.3 17124.9 17154.3 17178.8 17198.2 8898.6 8910.2 9060.0 9101.6 9139.0 8711.7 8722.8 8751.7 8937.5 8962.6 5144.4 5156.3 5173.6 5198.0 5292.2 1985.9 2017.7 2082.7 2105.1 2106.3 948.5 1006.9 1033.2 1053.5 1065.3 20815.4 21257.5 21266.7 21363.9 21600.4 1025.8 1030.9 1057.1 1070.9 1145.9 11309.9 11867.5 11925.6 11962.6 12009.0 457679.0 458396.0 461967.0 558343.0 570415.0 57353.7 72457.0 102971.5 105424.0 109226.5 1040.3 1053.2 1073.8 1080.8 1086.8 19278.7 19323.0 19596.5 19630.3 19695.5 44881.6 45771.5 46371.2 46764.0 46780.8 57775.0 63861.4 63921.4 65121.7 65238.7 8420.0 8547.8 8682.5 8772.6 8833.8 10821.6 11831.4 11897.8 11996.0 12094.8 10467.3 10526.4 10535.7 10656.2 10965.1 10428.0 10490.3 10808.7 10891.8 10925.6 9796.9 10696.3 10867.0 10986.3 11252.6 8165.1 8570.5 8572.7 8579.5 8593.4 8847.7 9564.9 9639.3 10997.4 11086.2 04:04:21 INFO - PROCESS | 19599 | 04:04:21 INFO - PROCESS | 19599 | [#3] mootools.html Cycles:180 Average:6374.56 Median:455.63 stddev:3729.89 (818.6%) stddev-sans-first:3721.68 04:04:21 INFO - PROCESS | 19599 | Values: 1409.3 1445.2 1516.6 1665.9 1850.9 8399.1 8919.6 9257.9 9261.2 9509.8 9310.8 9358.1 9419.0 9426.3 9428.0 2490.1 2499.5 2516.1 2516.3 2522.0 2416.1 2477.6 2486.4 2487.7 2489.3 2429.8 2445.9 2490.9 2498.6 2499.5 2355.7 2377.8 2380.0 2459.4 2460.6 2380.0 2382.2 2408.8 2412.7 2417.9 9374.9 9381.0 9700.3 9816.6 9818.3 5625.5 5836.9 5847.2 5849.7 5854.6 3615.7 3747.4 3775.7 3829.0 3834.1 1159.0 1160.0 1169.7 1198.1 1201.3 14095.7 14182.3 14228.9 14239.4 14241.0 4743.1 4780.6 4793.3 4886.0 4990.5 10514.2 10516.8 10518.6 10526.2 10527.8 5592.3 5605.6 5610.2 5610.3 5610.7 4717.1 4760.0 5122.7 5178.8 5200.3 4162.4 4169.8 4213.1 4221.9 4295.9 1234.5 1239.5 1254.2 1261.5 1285.7 1073.9 1096.7 1103.6 1106.7 1118.1 6533.4 6849.6 6865.1 6909.4 6942.8 452.4 455.6 455.7 463.6 471.5 4376.1 4381.9 4421.3 4449.4 4481.3 12077.3 12439.1 12534.4 12548.5 12555.5 11635.2 12381.7 12383.5 12414.7 12415.7 8729.4 9178.2 9187.4 9190.0 9191.9 6714.3 6768.0 6868.0 6915.9 7006.9 10966.6 11368.3 11433.5 11445.7 11540.2 9592.5 10490.5 11745.2 11760.4 11890.0 8570.3 8987.1 8991.9 9001.1 9018.6 9192.6 9270.9 9350.9 9571.7 9676.2 8401.2 8496.8 8709.3 8796.1 8990.2 8247.1 8268.5 8268.9 8593.1 8617.0 8064.7 8178.0 8184.9 8190.1 8198.5 5692.2 5765.2 5823.6 5852.9 5900.4 5889.9 5903.9 5953.0 5990.2 6032.5 04:04:21 INFO - PROCESS | 19599 | 04:04:21 INFO - PROCESS | 19599 | [#4] prototype.html Cycles:180 Average:4845.62 Median:5563.01 stddev:1401.73 (25.2%) stddev-sans-first:1364.51 04:04:21 INFO - PROCESS | 19599 | Values: 353.2 355.9 358.7 361.8 363.8 5495.2 5523.7 5545.7 5557.7 5568.3 5479.4 5511.6 5521.4 5542.0 5553.8 4029.3 4078.8 4275.7 4294.8 4297.4 4314.4 4337.2 4346.7 4367.1 4367.5 4273.3 4283.1 4285.5 4292.0 4342.3 4097.5 4126.5 4254.9 4302.5 4323.9 3818.4 3829.3 3839.9 3844.8 3855.2 5302.6 5304.7 5312.5 5323.7 5332.6 4801.3 4833.9 4896.4 4911.0 4920.5 5619.5 5622.0 5628.9 5632.7 5636.2 4233.2 4233.3 4246.0 4269.9 4286.4 5710.3 5848.3 5875.9 5881.4 5942.8 4347.1 4351.8 4363.1 4363.3 4378.3 4420.4 4422.7 4425.6 4427.9 4436.8 4802.4 4870.0 4883.4 4920.7 4948.9 5612.0 5617.4 5618.2 5634.6 5680.8 1498.6 1503.3 1522.8 1523.6 1532.4 974.3 978.8 986.3 993.8 1009.2 5446.7 5509.4 5571.5 5606.7 5613.3 5870.3 5888.2 5889.8 5899.4 5910.5 5306.6 5574.8 5575.7 5582.2 5595.9 3079.0 3103.1 3105.9 3112.1 3116.0 5845.4 6091.2 6098.3 6103.4 6113.8 5890.3 5941.8 5941.8 5952.3 5965.6 6100.5 6103.0 6104.2 6129.7 6140.2 5685.4 5821.2 5846.5 5851.2 5864.2 5765.0 5785.6 5787.3 5802.4 5804.7 6001.7 6048.0 6073.0 6135.1 6151.6 4250.1 4281.7 4282.7 4293.6 4296.7 5880.5 5897.2 5903.9 5905.9 5915.3 5539.6 5682.3 5734.6 5783.2 5787.9 5723.3 5753.5 5756.6 5769.8 5779.6 5419.7 5669.3 5712.0 5712.1 5732.5 5182.4 5724.2 5728.9 5745.4 5748.2 5592.0 5608.2 5617.6 5628.0 5646.8 04:04:21 INFO - PROCESS | 19599 | 04:04:21 INFO - PROCESS | 19599 | [#5] yui.html Cycles:180 Average:22478.09 Median:3893.19 stddev:33055.63 (849.1%) stddev-sans-first:33108.31 04:04:21 INFO - PROCESS | 19599 | Values: 807.8 823.5 849.6 851.5 859.5 14903.8 15758.5 15775.3 15776.6 15791.8 15283.2 15518.7 15556.4 15562.1 15582.0 711.1 843.2 850.0 852.5 880.5 879.2 880.7 885.2 886.1 886.8 922.7 923.2 925.2 926.0 926.8 761.1 894.7 917.5 919.4 927.8 678.1 678.7 678.8 678.8 679.0 13397.3 13409.7 13498.2 13509.0 13541.7 15172.3 15371.8 15470.4 15502.9 15692.5 14239.4 14266.5 14366.4 14393.5 14399.6 4551.4 4615.4 4639.4 4688.1 4722.9 84508.2 85858.0 88681.0 88733.0 89286.6 12035.8 12503.2 12638.9 12657.2 12794.0 23546.0 23576.5 23643.3 23735.5 23766.4 8596.1 8840.8 8842.1 8868.3 9294.5 4706.6 4815.2 4872.7 4958.4 5061.7 3750.4 3884.4 3892.2 3892.4 3893.9 598.2 622.4 632.6 645.5 658.6 631.4 665.6 669.5 725.5 768.5 10156.7 10737.2 10821.8 10882.9 10977.1 659.2 693.1 708.1 740.0 790.8 4802.5 4994.6 5047.3 5055.9 5057.8 74082.0 118078.8 118787.4 130302.0 131912.3 111786.4 112255.4 112821.4 113342.0 117683.0 63441.0 99963.5 100840.5 101347.5 102060.0 9713.9 9761.2 9840.4 9846.8 9967.0 58319.0 58981.0 59145.0 59853.7 60698.7 79913.6 85294.6 85371.6 87287.0 88988.6 12751.9 12869.2 12873.9 12876.2 12993.1 14130.4 14165.9 14270.3 14383.6 14653.8 13041.5 13284.5 13338.7 13758.2 13908.5 13487.4 13637.2 13907.8 14215.9 14499.1 12941.7 13147.0 13299.0 13347.8 13947.9 10430.4 10564.0 10706.6 11010.0 11265.3 10124.3 11136.2 11217.6 11543.2 11624.7 04:04:21 INFO - PROCESS | 19599 | -------- Summary: end -------- 04:04:21 INFO - PROCESS | 19599 | 04:04:22 INFO - TEST-INFO | 19599: exit 0 04:04:22 INFO - TEST-OK | dromaeo_css | took 1113597ms 04:04:22 INFO - TEST-START | kraken 04:04:22 INFO - operating with platform_type : linux_ 04:04:22 INFO - Initialising browser for kraken test... 04:04:22 INFO - TEST-INFO | started process 19805 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV4Oh8a/profile http://localhost:48506/getInfo.html) 04:04:26 INFO - PROCESS | 19805 | __metrics Screen width/height:1600/1200 04:04:26 INFO - PROCESS | 19805 | colorDepth:24 04:04:26 INFO - PROCESS | 19805 | Browser inner width/height: 1024/697 04:04:26 INFO - PROCESS | 19805 | __metrics 04:04:27 INFO - PROCESS | 19805 | 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" 04:04:27 INFO - PROCESS | 19805 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:04:28 INFO - TEST-INFO | 19805: exit 0 04:04:28 INFO - Browser initialized. 04:04:28 INFO - Running cycle 1/1 for kraken test... 04:04:28 INFO - TEST-INFO | started process 19893 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpV4Oh8a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:04:39 INFO - PROCESS | 19893 | RSS: Main: 145408000 04:04:39 INFO - PROCESS | 19893 | 04:04:42 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:48506/tests/kraken/driver.html?testName=audio-beat-detection) 04:04:43 INFO - PROCESS | 19893 | RSS: Main: 191385600 04:04:43 INFO - PROCESS | 19893 | 04:04:45 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:48506/tests/kraken/driver.html?testName=audio-dft) 04:04:46 INFO - PROCESS | 19893 | RSS: Main: 181276672 04:04:46 INFO - PROCESS | 19893 | 04:04:49 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:48506/tests/kraken/driver.html?testName=audio-fft) 04:04:49 INFO - PROCESS | 19893 | RSS: Main: 269791232 04:04:49 INFO - PROCESS | 19893 | 04:04:51 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:48506/tests/kraken/driver.html?testName=audio-oscillator) 04:04:52 INFO - PROCESS | 19893 | RSS: Main: 175063040 04:04:52 INFO - PROCESS | 19893 | 04:04:54 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:48506/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:04:55 INFO - PROCESS | 19893 | RSS: Main: 176959488 04:04:55 INFO - PROCESS | 19893 | 04:04:58 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:48506/tests/kraken/driver.html?testName=imaging-darkroom) 04:04:58 INFO - PROCESS | 19893 | RSS: Main: 213331968 04:04:58 INFO - PROCESS | 19893 | 04:05:02 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:48506/tests/kraken/driver.html?testName=imaging-desaturate) 04:05:02 INFO - PROCESS | 19893 | RSS: Main: 211832832 04:05:02 INFO - PROCESS | 19893 | 04:05:05 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:48506/tests/kraken/driver.html?testName=json-parse-financial) 04:05:06 INFO - PROCESS | 19893 | RSS: Main: 211533824 04:05:06 INFO - PROCESS | 19893 | 04:05:07 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:48506/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:05:08 INFO - PROCESS | 19893 | RSS: Main: 175665152 04:05:08 INFO - PROCESS | 19893 | 04:05:10 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:48506/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:05:10 INFO - PROCESS | 19893 | RSS: Main: 181858304 04:05:10 INFO - PROCESS | 19893 | 04:05:12 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:48506/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:05:13 INFO - PROCESS | 19893 | RSS: Main: 186474496 04:05:13 INFO - PROCESS | 19893 | 04:05:15 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:48506/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:05:16 INFO - PROCESS | 19893 | RSS: Main: 181837824 04:05:16 INFO - PROCESS | 19893 | 04:05:19 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:48506/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:05:19 INFO - PROCESS | 19893 | RSS: Main: 183644160 04:05:19 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | Cycle 1(1): loaded http://localhost:48506/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:48506/tests/kraken/driver.html?testName=ai-astar) 04:05:21 INFO - PROCESS | 19893 | RSS: Main: 235958272 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | __start_tp_report 04:05:21 INFO - PROCESS | 19893 | _x_x_mozilla_page_load 04:05:21 INFO - PROCESS | 19893 | _x_x_mozilla_page_load_details 04:05:21 INFO - PROCESS | 19893 | |i|pagename|runs| 04:05:21 INFO - PROCESS | 19893 | |0;ai-astar;104;110;111;111;111;110;110;110;111;111 04:05:21 INFO - PROCESS | 19893 | |1;audio-beat-detection;152;114;116;169;116;116;148;116;158;115 04:05:21 INFO - PROCESS | 19893 | |2;audio-dft;161;160;161;161;162;162;163;161;163;162 04:05:21 INFO - PROCESS | 19893 | |3;audio-fft;75;76;74;118;75;76;74;76;123;74 04:05:21 INFO - PROCESS | 19893 | |4;audio-oscillator;83;83;134;83;82;83;112;82;82;134 04:05:21 INFO - PROCESS | 19893 | |5;imaging-gaussian-blur;117;118;117;116;118;117;117;117;117;116 04:05:21 INFO - PROCESS | 19893 | |6;imaging-darkroom;146;146;146;146;146;145;145;145;145;145 04:05:21 INFO - PROCESS | 19893 | |7;imaging-desaturate;94;95;96;94;95;95;94;95;95;94 04:05:21 INFO - PROCESS | 19893 | |8;json-parse-financial;51;52;52;52;51;50;50;50;49;50 04:05:21 INFO - PROCESS | 19893 | |9;json-stringify-tinderbox;60;60;60;59;61;61;59;60;60;61 04:05:21 INFO - PROCESS | 19893 | |10;stanford-crypto-aes;77;72;83;72;78;73;73;82;78;73 04:05:21 INFO - PROCESS | 19893 | |11;stanford-crypto-ccm;125;118;118;126;119;120;118;120;166;119 04:05:21 INFO - PROCESS | 19893 | |12;stanford-crypto-pbkdf2;152;157;152;154;164;157;161;157;156;152 04:05:21 INFO - PROCESS | 19893 | |13;stanford-crypto-sha256-iterative;61;62;63;65;63;62;61;61;62;60 04:05:21 INFO - PROCESS | 19893 | __end_tp_report 04:05:21 INFO - PROCESS | 19893 | __start_cc_report 04:05:21 INFO - PROCESS | 19893 | _x_x_mozilla_cycle_collect,1649 04:05:21 INFO - PROCESS | 19893 | __end_cc_report 04:05:21 INFO - PROCESS | 19893 | __startTimestamp1471086321555__endTimestamp 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | ------- Summary: start ------- 04:05:21 INFO - PROCESS | 19893 | Number of tests: 14 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:2.13 (1.9%) stddev-sans-first:0.53 04:05:21 INFO - PROCESS | 19893 | Values: 104.0 110.0 111.0 111.0 111.0 110.0 110.0 110.0 111.0 111.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:132.00 stddev:21.95 (16.6%) stddev-sans-first:22.06 04:05:21 INFO - PROCESS | 19893 | Values: 152.0 114.0 116.0 169.0 116.0 116.0 148.0 116.0 158.0 115.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:1.00 04:05:21 INFO - PROCESS | 19893 | Values: 161.0 160.0 161.0 161.0 162.0 162.0 163.0 161.0 163.0 162.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#3] audio-fft Cycles:10 Average:84.10 Median:75.00 stddev:19.24 (25.7%) stddev-sans-first:20.12 04:05:21 INFO - PROCESS | 19893 | Values: 75.0 76.0 74.0 118.0 75.0 76.0 74.0 76.0 123.0 74.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:82.50 stddev:22.13 (26.8%) stddev-sans-first:22.98 04:05:21 INFO - PROCESS | 19893 | Values: 83.0 83.0 134.0 83.0 82.0 83.0 112.0 82.0 82.0 134.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 04:05:21 INFO - PROCESS | 19893 | Values: 117.0 118.0 117.0 116.0 118.0 117.0 117.0 117.0 117.0 116.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 04:05:21 INFO - PROCESS | 19893 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 145.0 145.0 145.0 145.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.67 04:05:21 INFO - PROCESS | 19893 | Values: 94.0 95.0 96.0 94.0 95.0 95.0 94.0 95.0 95.0 94.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:1.06 (2.1%) stddev-sans-first:1.12 04:05:21 INFO - PROCESS | 19893 | Values: 51.0 52.0 52.0 52.0 51.0 50.0 50.0 50.0 49.0 50.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 04:05:21 INFO - PROCESS | 19893 | Values: 60.0 60.0 60.0 59.0 61.0 61.0 59.0 60.0 60.0 61.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#10] stanford-crypto-aes Cycles:10 Average:76.10 Median:77.50 stddev:4.12 (5.3%) stddev-sans-first:4.36 04:05:21 INFO - PROCESS | 19893 | Values: 77.0 72.0 83.0 72.0 78.0 73.0 73.0 82.0 78.0 73.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:120.00 stddev:14.72 (12.3%) stddev-sans-first:15.62 04:05:21 INFO - PROCESS | 19893 | Values: 125.0 118.0 118.0 126.0 119.0 120.0 118.0 120.0 166.0 119.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.20 Median:157.00 stddev:3.99 (2.5%) stddev-sans-first:3.94 04:05:21 INFO - PROCESS | 19893 | Values: 152.0 157.0 152.0 154.0 164.0 157.0 161.0 157.0 156.0 152.0 04:05:21 INFO - PROCESS | 19893 | 04:05:21 INFO - PROCESS | 19893 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:62.00 stddev:1.41 (2.3%) stddev-sans-first:1.45 04:05:21 INFO - PROCESS | 19893 | Values: 61.0 62.0 63.0 65.0 63.0 62.0 61.0 61.0 62.0 60.0 04:05:21 INFO - PROCESS | 19893 | -------- Summary: end -------- 04:05:21 INFO - PROCESS | 19893 | 04:05:22 INFO - TEST-INFO | 19893: exit 0 04:05:22 INFO - TEST-OK | kraken | took 60000ms 04:05:22 INFO - SUITE-END | took 1173s 04:05:22 INFO - Completed test suite (00:19:33) 04:05:22 INFO - javascript benchmark 04:05:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3257.2019855135495, 3293.4895091260846, 3304.9919774468794, 3331.616864060634, 3342.3315336932606, 5579.7489112989915, 5642.915356269659, 5683.658980461175, 5708.1437784332375, 5709.1436284557285, 5631.662100273987, 5643.294588176478, 5714.1428785682165, 5719.942800572004, 5721.885562288751, 4973.776180071905, 5064.493550644943, 5159.0, 5171.198464238049, 5216.7391630418515, 5147.0, 5197.766100525486, 5208.973955130219, 5209.817656382027, 5213.113770658997, 5099.235114732783, 5120.795168193268, 5133.871653208662, 5139.331886854722, 5190.506901844319, 5218.452062533436, 5225.843224703265, 5230.08473517133, 5243.108671525831, 5272.815451459219, 4981.178105612569, 5010.298558201855, 5031.899362012776, 5137.691738495702, 5144.254083157921, 5502.064649009678, 5505.229267902457, 5525.475079867447, 5539.778408863641, 5581.358143813472, 6012.398760123996, 6023.939760602381, 6038.2150320458495, 6043.576949613529, 6060.030395136757, 5903.232579764646, 5915.467607915309, 6007.429294217042, 6013.36859629737, 6077.4226448487325, 5642.746076426571, 5662.943370566241, 5663.716814159276, 5668.093105103205, 5668.376478587352, 6145.723442445101, 6146.0473626587955, 6152.692365381713, 6157.568970172045, 6157.753689852445, 5822.33043200029, 5865.530757539386, 5917.585768996215, 5929.644221346733, 6001.279846418458, 6025.246844144482, 6030.487408570233, 6060.909086363708, 6080.0879868019265, 6086.9391306087255, 6019.367966363557, 6024.668643224665, 6028.246469191351, 6031.788887388921, 6031.8793624127275, 5844.211031510777, 5904.3210030847, 5911.704414779244, 5942.108683697394, 6025.0, 4569.702969306984, 4570.54294570547, 4578.061497393093, 4579.839705610288, 4579.862604121881, 4115.855945041909, 4128.649064829463, 4130.070734084867, 4136.462259906193, 4157.729747566459, 5578.107502799614, 5600.047991841397, 5621.7189140542805, 5653.830385088454, 5728.169415434739, 6046.848828779316, 6067.1202675611785, 6068.575199736064, 6068.908966365508, 6070.271567411851, 5747.971260143756, 5753.597248192586, 5757.510611598061, 5759.136129580596, 5760.0, 5097.413797413271, 5174.275601415805, 5219.765110570033, 5223.817166399158, 5273.209018647157, 6271.560990730696, 6276.968615156895, 6285.874282514233, 6290.24517057956, 6292.905606415908, 5985.132155837467, 6023.036314189709, 6028.3971602839365, 6035.185249991195, 6049.122877182695, 6128.601640893327, 6203.596766210362, 6211.09939058843, 6264.154339164154, 6304.968475157595, 5953.245100281329, 5964.940350596612, 5968.940310596839, 5978.043513038067, 6006.489448396936, 6072.787452439144, 6089.2997305310455, 6090.116933044772, 6092.908606370819, 6099.512039036777, 5760.366359700513, 5832.708364581669, 5837.620554663933, 5861.32594751609, 5932.79235226765, 5873.765049397976, 5880.911786323294, 5883.94116058834, 5887.735051922759, 5916.526677865846, 5806.2451881255165, 5822.068469045102, 5855.9121613176685, 5864.266966629171, 5872.471477567039, 5790.855228619318, 5847.561432892465, 5863.794767183128, 5895.351511333835, 5973.104034394875, 5765.4811067004175, 5766.884662306814, 5768.077107662753, 5772.134179873053, 5772.855678608068], "value": 5560.140935771125, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.7164818359462, 634.3054355480748, 650.4893658478093, 661.2131563439508, 671.090672139251, 35770.64229357709, 47213.763931180336, 48052.51947480524, 48115.27827082597, 48217.0, 49551.0, 49897.0, 49985.75007124959, 49995.250071248964, 50047.0, 8678.913210867873, 9321.254299656011, 9380.718578442658, 9452.10205030521, 9464.432134071978, 10949.178811589123, 11205.0, 11230.775384492346, 11520.42397880107, 11547.076233901267, 11411.657650270505, 11501.252418592807, 11514.366709830956, 11516.251443656178, 11518.596849110283, 11301.943490282538, 11341.603043893425, 11357.034652054585, 11360.261582997078, 11365.943170284178, 11183.161262905232, 11237.943810280978, 11247.887521124765, 11251.662450126427, 11255.155863310285, 45441.31838022432, 45554.316685249745, 45736.0, 45750.313745293846, 45774.08451830978, 3666.2850744104644, 3967.960320396788, 4015.5382131054716, 4217.430646862665, 4359.10638319147, 33767.662323376695, 34737.652623473696, 34814.47778283328, 35208.64791352105, 36994.8150259247, 4209.5579964103945, 4284.100338928829, 4289.099289149282, 4289.442372491588, 4338.3058710606465, 381776.0, 381888.0, 382957.0, 383277.9999999972, 384328.0, 65247.67376163089, 65634.0, 65649.67175164189, 65729.0, 65769.67115164394, 40437.59562404397, 41202.38196427056, 41294.1741165181, 42728.78635606802, 43164.0, 19728.210871565265, 19895.60208795816, 19923.302684405975, 19931.10310036052, 19941.0, 13365.198088114745, 14577.344019519147, 14626.926865365605, 14669.853301466848, 15006.62483437901, 21135.0, 21346.252881149398, 21457.570848582942, 21463.141474341162, 21468.355949321547, 7573.841455185246, 7627.618619069135, 7681.500702454323, 7707.537547747153, 7733.961330193426, 1050.7005503431485, 1092.306385445232, 1114.3258328711013, 1184.3130984029242, 1214.2532342609231, 34369.82815085909, 34949.30101397958, 35071.4739278911, 35182.472262916075, 35188.64811351905, 1135.5117299561268, 1139.9544018239178, 1153.348358177627, 1158.976820463586, 1216.8478940132484, 17328.653426931643, 18142.274309027493, 18365.265389384276, 18434.446966591022, 18541.35105271336, 583917.0, 589642.0, 590268.0, 591498.0, 592941.0, 64046.35953640497, 64630.353696463375, 64780.0, 64811.67594161999, 64870.67564662241, 1355.8372995240634, 1419.0824262824049, 1452.3464441001381, 1497.3037537544876, 1499.3777582303221, 33586.49620255649, 35912.4613130798, 36124.0, 36297.45553816642, 36391.27217455583, 55537.444625553224, 56272.71863640656, 56273.718631406584, 56297.71851140718, 56367.71816140893, 421140.0, 421402.0, 421847.0, 424905.0, 426163.0, 30815.53776693396, 31928.52107218347, 32154.356912861145, 33409.665903340654, 33416.832915835264, 53894.0, 54031.0, 54123.0, 54231.0, 54297.45702542924, 43522.84663211884, 44601.33098003597, 44794.77602611966, 45659.0, 46678.53321466742, 45649.31526027046, 45788.31317530306, 46269.768651156526, 46373.30440043334, 47316.2902556455, 44878.326825097, 45600.0, 45904.540954591364, 46300.30549541692, 46322.53677463182, 38332.80833595926, 39419.80290098531, 39739.40390894081, 40579.0, 42952.78523607362, 29219.85390073036, 29234.322647157987, 29573.704262957956, 29683.0, 29766.702332976394], "value": 22069.266618945105, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.70247314514114, 402.06920977936096, 423.0966885699033, 423.7224617875292, 427.568156162276, 17528.0, 20598.279060232893, 20747.688784668222, 20955.47611309716, 21256.8937155314, 19805.306814261505, 20174.596508069866, 20601.587968240627, 20714.0, 20973.265935692252, 5610.943890561093, 5921.970390148043, 5964.493018093467, 5970.79102231422, 5973.432523910222, 4323.0, 5909.911351329712, 5985.431384018533, 6420.614763114205, 6694.631795251259, 6697.229818570853, 6745.392914637681, 6751.25736169021, 6752.561083529579, 6757.358050985174, 6488.643124628144, 6683.0, 6687.264400915896, 6718.496112791511, 6727.4618030557685, 5793.275840519935, 5963.13534537494, 5965.4929331006915, 5997.820065398045, 6016.75932962681, 25267.241982740365, 25374.0, 25406.36484087894, 25525.0, 25555.105571305103, 2557.539642864284, 2622.93442663933, 2625.829321094123, 2656.2296933889147, 3070.6775788542104, 14539.200343981076, 15168.24158792056, 15711.135887526181, 15729.449469268635, 16044.75932861008, 2008.337248707923, 2085.7914208579023, 2096.1929657081982, 2129.9254526091668, 2198.219632030638, 84184.57907710422, 92212.53893730488, 92736.07263927342, 93233.06766932312, 93239.53380233055, 11398.430078496207, 11545.74857902701, 11746.882531174579, 11951.521939122511, 12011.33937633421, 17071.317147314217, 17124.914375428292, 17154.313827446764, 17178.828211717973, 17198.226079826436, 8898.644054237759, 8910.153535414125, 9060.0, 9101.635934562544, 9138.954305228564, 8711.695090671796, 8722.825543489094, 8751.693690720795, 8937.508437035895, 8962.551872406353, 5144.408393034774, 5156.303898973591, 5173.560247379015, 5197.97401013, 5292.179712144623, 1985.9106340214726, 2017.7074324222906, 2082.666773316262, 2105.1368938735045, 2106.325975687765, 948.5209968965628, 1006.9395836249702, 1033.2199189611795, 1053.4837929414532, 1065.3181963543338, 20815.37553873386, 21257.468563286042, 21266.680999785014, 21363.893180533996, 21600.351989440343, 1025.779457416659, 1030.9278350515542, 1057.1278695076592, 1070.903618674323, 1145.9255148415327, 11309.886901131047, 11867.465964031639, 11925.642230733092, 11962.641120766391, 12009.039276857831, 457679.0, 458396.0, 461967.0, 558343.0, 570415.0, 57353.71323143441, 72457.0, 102971.4851425738, 105424.0, 109226.45386773015, 1040.2977989856815, 1053.2469284461647, 1073.8013467508536, 1080.783843231357, 1086.7500474890662, 19278.710819338, 19323.0, 19596.510087247934, 19630.312939047064, 19695.507612309808, 44881.55118448774, 45771.542284576724, 46371.161734144276, 46764.0, 46780.766096170664, 57775.0, 63861.3613863874, 63921.3607863934, 65121.67439162774, 65238.673806630664, 8419.958939213158, 8547.786305342415, 8682.479051256945, 8772.605232764668, 8833.823323533365, 10821.567137314734, 11831.40842957866, 11897.82153267719, 11996.0, 12094.758104837678, 10467.319624224401, 10526.368417894952, 10535.736606584896, 10656.200784941006, 10965.122790176927, 10428.0, 10490.31812932157, 10808.675739727507, 10891.782164356828, 10925.562977480811, 9796.85304720444, 10696.304740191863, 10867.0, 10986.340819550853, 11252.606158784403, 8165.142660020612, 8570.48577085377, 8572.699955501528, 8579.485230886166, 8593.44142630727, 8847.69033083839, 9564.904350956402, 9639.325247232626, 10997.395143267197, 11086.168537359568], "value": 9771.467612306067, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.3094383751964, 1445.1979151570881, 1516.5753588995083, 1665.9167041647916, 1850.935217267395, 8399.076101628823, 8919.598618062195, 9257.861132083024, 9261.166495015448, 9509.80980380392, 9310.767230819232, 9358.110979456958, 9419.0, 9426.34015618907, 9427.95286023569, 2490.091116742385, 2499.5000999800022, 2516.1319344826006, 2516.2954372775653, 2522.0, 2416.1422694943217, 2477.55404027275, 2486.4032632168332, 2487.7263501014872, 2489.26566662834, 2429.7691719286727, 2445.877706114696, 2490.9252722418264, 2498.600223964166, 2499.487605040962, 2355.6819829322994, 2377.821663375245, 2380.036085385413, 2459.3728599207266, 2460.5817011108156, 2380.024190082075, 2382.201962342617, 2408.843425177358, 2412.6863507744056, 2417.8791060447084, 9374.85937710935, 9381.0, 9700.27247956406, 9816.607335706563, 9818.26363022776, 5625.54995600351, 5836.9124463133085, 5847.210626565402, 5849.678267695275, 5854.560907931879, 3615.6745892869503, 3747.381682022463, 3775.716821238391, 3829.0235989822695, 3834.0989867381145, 1159.0354349377808, 1159.9560395643903, 1169.7075731067234, 1198.1373411143963, 1201.285235285004, 14095.718085638333, 14182.29088545579, 14228.857711422961, 14239.430422783076, 14241.0, 4743.146233677971, 4780.569748722632, 4793.257045158006, 4885.9755701221975, 4990.451050384455, 10514.158867290598, 10516.842247366296, 10518.579256829642, 10526.157907367393, 10527.84208236877, 5592.328920529562, 5605.607607467438, 5610.158476228598, 5610.298712660918, 5610.663360198401, 4717.127331443694, 4760.0, 5122.667026643257, 5178.844634660966, 5200.2979597754575, 4162.375643653476, 4169.76257842293, 4213.094184750232, 4221.936670949938, 4295.935560966588, 1234.4630085912656, 1239.4980033086615, 1254.1910467748435, 1261.4512686981193, 1285.7235694325766, 1073.9087177589993, 1096.7203363142348, 1103.5530610102921, 1106.6956586938657, 1118.1334465789078, 6533.41199292066, 6849.623270720059, 6865.0732151159955, 6909.447244220451, 6942.757003504954, 452.3937923182885, 455.59224494077614, 455.67419295204087, 463.59203900567286, 471.5284715284715, 4376.124775045004, 4381.9123617527475, 4421.292593185075, 4449.377087207794, 4481.282994720829, 12077.335746534025, 12439.129260951828, 12534.435950382254, 12548.498060077494, 12555.497780088877, 11635.185537012328, 12381.690457738448, 12383.504659813687, 12414.689632759073, 12415.689607759878, 8729.388942773945, 9178.173964343241, 9187.356885017985, 9190.0, 9191.89697236336, 6714.261431242657, 6768.0, 6867.965660171667, 6915.930840691529, 7006.929930700831, 10966.616168434066, 11368.317900925971, 11433.542658293574, 11445.656630300771, 11540.192186546861, 9592.472414017297, 10490.527926243185, 11745.236559623598, 11760.411979400824, 11890.048796096118, 8570.314374849871, 8987.056359082464, 8991.865122023044, 9001.144891235323, 9018.639254429749, 9192.586333615138, 9270.860937085814, 9350.906490935004, 9571.712848614552, 9676.225901927688, 8401.201885820838, 8496.830063398822, 8709.303255739653, 8796.07641197665, 8990.190882820578, 8247.134050924567, 8268.545230012167, 8268.875966860382, 8593.140685931357, 8616.956915215384, 8064.677412903418, 8178.0, 8184.918150818416, 8190.058143313595, 8198.508089514648, 5692.203091567267, 5765.192872997867, 5823.621464604787, 5852.882942341214, 5900.409959004237, 5889.911651325147, 5903.881922361615, 5953.0, 5990.191324171181, 6032.4570788629235], "value": 4947.320997613523, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.15948043656095, 355.8558783692604, 358.7112374538496, 361.82270687363183, 363.7999100494727, 5495.230667706524, 5523.7238138093035, 5545.694986775726, 5557.72211389431, 5568.303962004749, 5479.397266300703, 5511.559075273978, 5521.426393477859, 5541.972290138554, 5553.7778488860395, 4029.294873397144, 4078.816453259603, 4275.679324050693, 4294.828206871722, 4297.355396690506, 4314.37441570972, 4337.175936572041, 4346.739195648255, 4367.08291258836, 4367.497737760151, 4273.337632666926, 4283.121959998209, 4285.485741710999, 4291.97854010731, 4342.3052311630145, 4097.549269580344, 4126.524085548677, 4254.936175957363, 4302.548232435581, 4323.870283891489, 3818.4344418788164, 3829.2915810574855, 3839.9232015359817, 3844.826982785781, 3855.20968201518, 5302.602304827154, 5304.708241046741, 5312.548433383167, 5323.653962492425, 5332.573394128499, 4801.255805350176, 4833.879153021167, 4896.387951506062, 4910.950890491085, 4920.532549407801, 5619.494245517923, 5621.971890140523, 5628.887422251573, 5632.71836408182, 5636.239107720447, 4233.174530966463, 4233.322668373076, 4246.0, 4269.935950960738, 4286.39990401344, 5710.2862142232225, 5848.327442344143, 5875.941240587582, 5881.3530511643685, 5942.7920022799, 4347.108842687241, 4351.782410879507, 4363.127374525107, 4363.2800587903275, 4378.277584198571, 4420.447444069491, 4422.690411671152, 4425.6238219751685, 4427.889302767392, 4436.778161091933, 4802.399700037496, 4869.975650121798, 4883.438404583519, 4920.72935988524, 4948.911239527299, 5612.045952188138, 5617.38208797032, 5618.2415373924, 5634.605577609528, 5680.829575112753, 1498.625343664084, 1503.3159912239905, 1522.7868098466224, 1523.5581681312294, 1532.387045181914, 974.2985050763439, 978.7846673731767, 986.3046552180696, 993.8260804359209, 1009.2027298434301, 5446.673199608036, 5509.429812503417, 5571.526420254243, 5606.747696353674, 5613.270274864241, 5870.3249126350165, 5888.205092312483, 5889.793857215062, 5899.410058994066, 5910.527157827363, 5306.62853600252, 5574.804881829116, 5575.749091290902, 5582.190582365615, 5595.916061259084, 3079.0, 3103.115612050555, 3105.9223519411744, 3112.1130477813713, 3116.0213264577155, 5845.444682755132, 6091.208142941389, 6098.32918378978, 6103.42017508336, 6113.786017489456, 5890.293164820163, 5941.762329506858, 5941.762329506858, 5952.345242023374, 5965.582409231399, 6100.468305653193, 6102.9694851525455, 6104.17593624855, 6129.693515324216, 6140.2017737694705, 5685.374608792947, 5821.224526519452, 5846.538365408648, 5851.151583020353, 5864.179014937998, 5764.962306784819, 5785.595008349544, 5787.334456537552, 5802.4197580241635, 5804.709764511842, 6001.699915004145, 6047.969760151347, 6073.0, 6135.141080248858, 6151.569390142647, 4250.12872361159, 4281.700280980302, 4282.700210985326, 4293.613574778285, 4296.656267498655, 5880.529557635294, 5897.196308381156, 5903.940960590339, 5905.852353691193, 5915.290165180206, 5539.556835453234, 5682.3237145041485, 5734.569907257056, 5783.16144159103, 5787.94212057874, 5723.313202415737, 5753.453421924911, 5756.56825738063, 5769.826905192851, 5779.624324418899, 5419.674819510684, 5669.262995810518, 5712.0, 5712.143178523254, 5732.455416735404, 5182.352205974254, 5724.227229323988, 5728.914066288926, 5745.425457454388, 5748.166515855261, 5592.0, 5608.158776183605, 5617.612514681163, 5628.0, 5646.830595082154], "value": 4403.794967171008, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [807.838432313537, 823.493551465848, 849.5794581682067, 851.5359129274544, 859.5100792548247, 14903.776443353345, 15758.527244182678, 15775.290111944962, 15776.605584860385, 15791.842081579181, 15283.159426231552, 15518.68962620747, 15556.377744890218, 15562.144082075481, 15582.0, 711.0507472524187, 843.1863251961855, 849.995750021249, 852.4842470305468, 880.5201165364891, 879.226280872831, 880.6609455359669, 885.2254277507182, 886.0696268917643, 886.8226354729047, 922.7324076017946, 923.201430762389, 925.181214625055, 926.0276709455045, 926.8378033844084, 761.1285078585017, 894.7047474333455, 917.4907926101012, 919.4253591505309, 927.8237134944407, 678.098129487785, 678.725116327888, 678.8268991407209, 678.8472593666435, 678.9592624442549, 13397.263150526716, 13409.66475838102, 13498.190108593417, 13509.0, 13541.729165416637, 15172.317245723969, 15371.846281537153, 15470.381184752596, 15502.922485387613, 15692.529224123295, 14239.43042278318, 14266.500672476413, 14366.425342986267, 14393.496227631982, 14399.568012959628, 4551.385562949025, 4615.423072115986, 4639.443266807971, 4688.062387522509, 4722.858314250578, 84508.15491845064, 85858.0, 88681.0, 88733.0, 89286.55356723304, 12035.759284814254, 12503.249805011728, 12638.873611263769, 12657.177283476545, 12794.040446966515, 23546.0, 23576.528469430858, 23643.290701278987, 23735.525289494115, 23766.405839853796, 8596.140385961355, 8840.778980525409, 8842.115788421106, 8868.33487488441, 9294.502877555044, 4706.599939005155, 4815.15734746418, 4872.731999740048, 4958.404991401055, 5061.746912654353, 3750.381187104097, 3884.378499440076, 3892.2020985697864, 3892.4355968384425, 3893.9415908761393, 598.1565991951328, 622.3807311724863, 632.6330728177646, 645.4642646603309, 658.5850913924197, 631.36232405271, 665.6405541007898, 669.4677731203705, 725.5066554742825, 768.5196752029981, 10156.746081348025, 10737.194710396596, 10821.783564328669, 10882.945585272022, 10977.12183025372, 659.1595715462823, 693.0955103589864, 708.0759608710682, 739.9630018499054, 790.7509134622576, 4802.519748025239, 4994.5754610858485, 5047.293378926953, 5055.949440505621, 5057.772400241998, 74082.0, 118078.8192118085, 118787.40606296914, 130302.0, 131912.3404382972, 111786.44106779576, 112255.43872280586, 112821.43589282165, 113342.0, 117682.99999999828, 63441.0, 99963.50018249862, 100840.49579752202, 101347.49326253468, 102059.97940020653, 9713.854292185482, 9761.219102471643, 9840.409575425498, 9846.753831154278, 9967.0, 58319.0, 58981.0, 59145.0, 59853.700731496065, 60698.69650651718, 79913.60043199747, 85294.57352713197, 85371.57314213389, 87287.0, 88988.5550572243, 12751.936240318739, 12869.227846328875, 12873.871261287268, 12876.163049401759, 12993.136585438553, 14130.434782608992, 14165.858341416453, 14270.286485675882, 14383.640408989859, 14653.780193297322, 13041.478340866259, 13284.535041273895, 13338.666533336744, 13758.243296618783, 13908.51320203788, 13487.393067311797, 13637.18176909349, 13907.791383129059, 14215.857841421453, 14499.13005219648, 12941.676458088246, 13147.0, 13299.0, 13347.799783003069, 13947.930260348634, 10430.426326552113, 10564.0, 10706.571737130427, 11010.0, 11265.324080555192, 10124.291299608956, 11136.164787640797, 11217.60738374153, 11543.249688770204, 11624.709382265512], "value": 7968.935129831551, "unit": "score"}], "name": "dromaeo_css", "value": 7698.615030907886, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 110.0, 111.0, 111.0, 111.0, 110.0, 110.0, 110.0, 111.0, 111.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 114.0, 116.0, 169.0, 116.0, 116.0, 148.0, 116.0, 158.0, 115.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 161.0, 162.0, 162.0, 163.0, 161.0, 163.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 74.0, 118.0, 75.0, 76.0, 74.0, 76.0, 123.0, 74.0], "value": 84.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 134.0, 83.0, 82.0, 83.0, 112.0, 82.0, 82.0, 134.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 116.0, 118.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 52.0, 52.0, 51.0, 50.0, 50.0, 50.0, 49.0, 50.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 59.0, 61.0, 61.0, 59.0, 60.0, 60.0, 61.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 83.0, 72.0, 78.0, 73.0, 73.0, 82.0, 78.0, 73.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 118.0, 118.0, 126.0, 119.0, 120.0, 118.0, 120.0, 166.0, 119.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 157.0, 152.0, 154.0, 164.0, 157.0, 161.0, 157.0, 156.0, 152.0], "value": 156.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 65.0, 63.0, 62.0, 61.0, 61.0, 62.0, 60.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1470.6000000000001, "alertThreshold": 2.0}]} 04:05:22 INFO - Return code: 0 04:05:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:05:22 INFO - # TBPL SUCCESS # 04:05:22 INFO - Running post-action listener: _resource_record_post_action 04:05:22 INFO - [mozharness: 2016-08-13 11:05:22.870457Z] Finished run-tests step (success) 04:05:22 INFO - Running post-run listener: _resource_record_post_run 04:05:22 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 8192; Write bytes: 328589312; Read time: 164; Write time: 189808 04:05:22 INFO - TinderboxPrint: CPU usage
12.9% 04:05:22 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 164 04:05:22 INFO - TinderboxPrint: I/O write bytes / time
328,589,312 / 189,808 04:05:22 INFO - TinderboxPrint: CPU idle
8,269.4 (87.1%) 04:05:22 INFO - TinderboxPrint: CPU user
1,183.3 (12.5%) 04:05:22 INFO - TinderboxPrint: Swap in / out
0 / 0 04:05:22 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 78565376; Read time: 156; Write time: 52688 04:05:22 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 249118720; Read time: 0; Write time: 137096 04:05:23 INFO - Running post-run listener: _upload_blobber_files 04:05:23 INFO - Blob upload gear active. 04:05:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:05:23 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 04:05:23 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:05:23 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:05:23 INFO - (blobuploader) - INFO - Open directory for files ... 04:05:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:05:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:05:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:24 INFO - (blobuploader) - INFO - Done attempting. 04:05:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:05:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:05:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:25 INFO - (blobuploader) - INFO - Done attempting. 04:05:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:05:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:05:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:05:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:05:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:05:30 INFO - (blobuploader) - INFO - Done attempting. 04:05:30 INFO - (blobuploader) - INFO - Iteration through files over. 04:05:30 INFO - Return code: 0 04:05:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:05:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:05:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/819c33bb0e0f64ac81adbd96321ff3c97cc33968b6bcdb3819adbc257af7bd117fb7f8b4b07425f7f15e795e61b63296bfe5c5e024dcc928348f220a5d5aa6c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c1ebc97971db7e39a71ba4908b78e55e34036959b14d31c2b0f53cb274d4507ef56118ad407f676034018216f99d3b0447afdfc88165d58103e9c7bf425a7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e57c72935e21b5cb44e99ba6a4dff48c8a0372c2659130bcd4e789d2f01f498f1998093975c0eb64544b988885eb9953bd2715de234b780b36b36ce1e285ad04"} 04:05:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:05:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:05:30 INFO - Contents: 04:05:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/819c33bb0e0f64ac81adbd96321ff3c97cc33968b6bcdb3819adbc257af7bd117fb7f8b4b07425f7f15e795e61b63296bfe5c5e024dcc928348f220a5d5aa6c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c1ebc97971db7e39a71ba4908b78e55e34036959b14d31c2b0f53cb274d4507ef56118ad407f676034018216f99d3b0447afdfc88165d58103e9c7bf425a7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e57c72935e21b5cb44e99ba6a4dff48c8a0372c2659130bcd4e789d2f01f498f1998093975c0eb64544b988885eb9953bd2715de234b780b36b36ce1e285ad04"} 04:05:30 INFO - Running post-run listener: copy_logs_to_upload_dir 04:05:30 INFO - Copying logs to upload dir... 04:05:30 INFO - mkdir: /builds/slave/test/build/upload/logs 04:05:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.545323 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-08-13 04:05:29.227003) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-13 04:05:29.230491) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562 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-Aurora/sha512/819c33bb0e0f64ac81adbd96321ff3c97cc33968b6bcdb3819adbc257af7bd117fb7f8b4b07425f7f15e795e61b63296bfe5c5e024dcc928348f220a5d5aa6c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c1ebc97971db7e39a71ba4908b78e55e34036959b14d31c2b0f53cb274d4507ef56118ad407f676034018216f99d3b0447afdfc88165d58103e9c7bf425a7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e57c72935e21b5cb44e99ba6a4dff48c8a0372c2659130bcd4e789d2f01f498f1998093975c0eb64544b988885eb9953bd2715de234b780b36b36ce1e285ad04"} build_url:https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011202 build_url: 'https://queue.taskcluster.net/v1/task/Jgh3B9ANRsi9bKF63ZSnwg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/819c33bb0e0f64ac81adbd96321ff3c97cc33968b6bcdb3819adbc257af7bd117fb7f8b4b07425f7f15e795e61b63296bfe5c5e024dcc928348f220a5d5aa6c8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0c1ebc97971db7e39a71ba4908b78e55e34036959b14d31c2b0f53cb274d4507ef56118ad407f676034018216f99d3b0447afdfc88165d58103e9c7bf425a7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e57c72935e21b5cb44e99ba6a4dff48c8a0372c2659130bcd4e789d2f01f498f1998093975c0eb64544b988885eb9953bd2715de234b780b36b36ce1e285ad04"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-13 04:05:29.256400) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 04:05:29.256710) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1471084648.111912-1920609562 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004420 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-13 04:05:29.342024) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-13 04:05:29.342368) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-13 04:05:29.342654) ========= ========= Total master_lag: 0.18 =========