builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-036 starttime: 1472737944.63 results: success (0) buildid: 20160830104522 builduid: 5655a65e53c8465d859cdd3cc9117707 revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:24.640814) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:24.641465) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:24.641761) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:24.701722) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:24.701995) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 06:52:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2016-09-01 06:52:25 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.169385 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:24.905558) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:24.905840) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020788 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:24.968131) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:24.968435) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bac8c738419eb9ff1fe0947120e879707d19c97b --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 bac8c738419eb9ff1fe0947120e879707d19c97b --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=14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 06:52:25,385 truncating revision to first 12 chars 2016-09-01 06:52:25,385 Setting DEBUG logging. 2016-09-01 06:52:25,385 attempt 1/10 2016-09-01 06:52:25,385 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bac8c738419e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 06:52:25,778 unpacking tar archive at: mozilla-aurora-bac8c738419e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.725310 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.708334) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.708674) ========= script_repo_revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.709061) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.709327) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.719407) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 3 secs) (at 2016-09-01 06:52:25.719682) ========= /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=14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:52:26 INFO - MultiFileLogger online at 20160901 06:52:26 in /builds/slave/test 06:52:26 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 06:52:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:52:26 INFO - {'append_to_log': False, 06:52:26 INFO - 'base_work_dir': '/builds/slave/test', 06:52:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:52:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:52:26 INFO - 'branch': 'Mozilla-Aurora', 06:52:26 INFO - 'buildbot_json_path': 'buildprops.json', 06:52:26 INFO - 'config_files': ('talos/linux_config.py',), 06:52:26 INFO - 'default_actions': ('clobber', 06:52:26 INFO - 'read-buildbot-config', 06:52:26 INFO - 'download-and-extract', 06:52:26 INFO - 'populate-webroot', 06:52:26 INFO - 'create-virtualenv', 06:52:26 INFO - 'install', 06:52:26 INFO - 'run-tests'), 06:52:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:52:26 INFO - 'download_minidump_stackwalk': True, 06:52:26 INFO - 'download_symbols': 'ondemand', 06:52:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:52:26 INFO - 'tooltool.py': '/tools/tooltool.py', 06:52:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:52:26 INFO - '/tools/misc-python/virtualenv.py')}, 06:52:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:52:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:52:26 INFO - 'installer_path': 'installer.exe', 06:52:26 INFO - 'log_level': 'info', 06:52:26 INFO - 'log_name': 'talos', 06:52:26 INFO - 'log_to_console': True, 06:52:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:52:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:52:26 INFO - 'opt_config_files': (), 06:52:26 INFO - 'pip_index': False, 06:52:26 INFO - 'sps_profile': False, 06:52:26 INFO - 'sps_profile_interval': 0, 06:52:26 INFO - 'suite': 'dromaeojs', 06:52:26 INFO - 'system_bits': '32', 06:52:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:52:26 INFO - 'title': 'talos-linux64-ix-036', 06:52:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:52:26 INFO - 'use_talos_json': True, 06:52:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:52:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:52:26 INFO - 'work_dir': 'build'} 06:52:26 INFO - [mozharness: 2016-09-01 13:52:26.229636Z] Running clobber step. 06:52:26 INFO - Running pre-action listener: _resource_record_pre_action 06:52:26 INFO - Running main action method: clobber 06:52:26 INFO - rmtree: /builds/slave/test/build 06:52:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:52:26 INFO - Running post-action listener: _resource_record_post_action 06:52:26 INFO - [mozharness: 2016-09-01 13:52:26.692654Z] Finished clobber step (success) 06:52:26 INFO - [mozharness: 2016-09-01 13:52:26.692755Z] Running read-buildbot-config step. 06:52:26 INFO - Running pre-action listener: _resource_record_pre_action 06:52:26 INFO - Running main action method: read_buildbot_config 06:52:26 INFO - Using buildbot properties: 06:52:26 INFO - { 06:52:26 INFO - "project": "", 06:52:26 INFO - "product": "firefox", 06:52:26 INFO - "script_repo_revision": "production", 06:52:26 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 06:52:26 INFO - "repository": "", 06:52:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 06:52:26 INFO - "buildid": "20160830104522", 06:52:26 INFO - "pgo_build": "True", 06:52:26 INFO - "basedir": "/builds/slave/test", 06:52:26 INFO - "buildnumber": 437, 06:52:26 INFO - "slavename": "talos-linux64-ix-036", 06:52:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 06:52:26 INFO - "platform": "ubuntu64_hw", 06:52:26 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:52:26 INFO - "branch": "mozilla-aurora", 06:52:26 INFO - "repo_path": "releases/mozilla-aurora", 06:52:26 INFO - "revision": "bac8c738419eb9ff1fe0947120e879707d19c97b", 06:52:26 INFO - "stage_platform": "linux64-pgo", 06:52:26 INFO - "builduid": "5655a65e53c8465d859cdd3cc9117707", 06:52:26 INFO - "slavebuilddir": "test" 06:52:26 INFO - } 06:52:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 06:52:26 INFO - Running post-action listener: _resource_record_post_action 06:52:26 INFO - [mozharness: 2016-09-01 13:52:26.695272Z] Finished read-buildbot-config step (success) 06:52:26 INFO - [mozharness: 2016-09-01 13:52:26.695362Z] Running download-and-extract step. 06:52:26 INFO - Running pre-action listener: _resource_record_pre_action 06:52:26 INFO - Running main action method: download_and_extract 06:52:26 INFO - mkdir: /builds/slave/test/build/tests 06:52:26 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')]} 06:52:26 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 06:52:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:52:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:52:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:26 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:52:26 INFO - retry: Failed, sleeping 30 seconds before retrying 06:52:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:52:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:56 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:52:56 INFO - retry: Failed, sleeping 60 seconds before retrying 06:53:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:56 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:53:56 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:53:56 INFO - Caught exception: HTTP Error 404: Not Found 06:53:56 INFO - Caught exception: HTTP Error 404: Not Found 06:53:56 INFO - Caught exception: HTTP Error 404: Not Found 06:53:56 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:57 INFO - Downloaded 1361 bytes. 06:53:57 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:53:57 INFO - Using the following test package requirements: 06:53:57 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:57 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:57 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:53:57 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:57 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:53:57 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:57 INFO - u'jsshell-linux-x86_64.zip'], 06:53:57 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:57 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:53:57 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:53:57 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:57 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:53:57 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:57 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:53:57 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:57 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:53:57 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:53:57 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:53:57 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:53:57 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')]} 06:53:57 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:53:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:53:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:53:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:53:57 INFO - retry: Failed, sleeping 30 seconds before retrying 06:54:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:54:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:54:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:54:27 INFO - retry: Failed, sleeping 60 seconds before retrying 06:55:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:55:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:55:27 INFO - Caught exception: HTTP Error 404: Not Found 06:55:27 INFO - Caught exception: HTTP Error 404: Not Found 06:55:27 INFO - Caught exception: HTTP Error 404: Not Found 06:55:27 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:28 INFO - Downloaded 21769513 bytes. 06:55:28 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'] 06:55:28 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 06:55:29 INFO - Return code: 0 06:55:29 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 06:55:29 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')]} 06:55:29 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 06:55:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:55:29 INFO - retry: Failed, sleeping 30 seconds before retrying 06:55:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:55:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:55:59 INFO - retry: Failed, sleeping 60 seconds before retrying 06:56:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:56:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:56:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:56:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:56:59 INFO - Caught exception: HTTP Error 404: Not Found 06:56:59 INFO - Caught exception: HTTP Error 404: Not Found 06:56:59 INFO - Caught exception: HTTP Error 404: Not Found 06:56:59 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:56:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:56:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:00 INFO - Downloaded 21769513 bytes. 06:57:00 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'] 06:57:00 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 06:57:01 INFO - Return code: 0 06:57: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')]} 06:57:01 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 06:57:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:57:01 INFO - retry: Failed, sleeping 30 seconds before retrying 06:57:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:57:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:57:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:57:31 INFO - retry: Failed, sleeping 60 seconds before retrying 06:58:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:58:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 06:58:31 INFO - Caught exception: HTTP Error 404: Not Found 06:58:31 INFO - Caught exception: HTTP Error 404: Not Found 06:58:31 INFO - Caught exception: HTTP Error 404: Not Found 06:58:31 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 06:58:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:32 INFO - Downloaded 14413723 bytes. 06:58:32 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'] 06:58:32 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 06:58:32 INFO - Return code: 0 06:58:32 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')]} 06:58:32 INFO - https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 06:58:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:58:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:58:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:58:32 INFO - retry: Failed, sleeping 30 seconds before retrying 06:59:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 06:59:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 06:59:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:59:02 INFO - retry: Failed, sleeping 60 seconds before retrying 07:00:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 07:00:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:03 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:00:03 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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! 07:00:03 INFO - Caught exception: HTTP Error 404: Not Found 07:00:03 INFO - Caught exception: HTTP Error 404: Not Found 07:00:03 INFO - Caught exception: HTTP Error 404: Not Found 07:00:03 INFO - trying https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:03 INFO - Downloading https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 07:00:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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 07:00:04 INFO - Downloaded 63889067 bytes. 07:00:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:04 INFO - mkdir: /builds/slave/test/properties 07:00:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:00:04 INFO - Writing to file /builds/slave/test/properties/build_url 07:00:04 INFO - Contents: 07:00:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:00:05 INFO - Running post-action listener: _resource_record_post_action 07:00:05 INFO - Running post-action listener: set_extra_try_arguments 07:00:05 INFO - [mozharness: 2016-09-01 14:00:05.641900Z] Finished download-and-extract step (success) 07:00:05 INFO - [mozharness: 2016-09-01 14:00:05.642136Z] Running populate-webroot step. 07:00:05 INFO - Running pre-action listener: _resource_record_pre_action 07:00:05 INFO - Running main action method: populate_webroot 07:00:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:00:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:00:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:00:05 INFO - '--tppagecycles', 07:00:05 INFO - '7'], 07:00:05 INFO - 'tests': ['tsvgm']}}, 07:00:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:00:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:00:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:00:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:00:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:00:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:00:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:05 INFO - 'talos_options': ['--disable-e10s'], 07:00:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:05 INFO - 'talos_options': ['--disable-e10s'], 07:00:05 INFO - 'tests': ['damp', 'tps']}, 07:00:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:05 INFO - 'tests': ['damp', 'tps']}, 07:00:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:00:05 INFO - 'tests': ['dromaeo_dom']}, 07:00:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:00:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:00:05 INFO - 'tests': ['basic_compositor_video']}, 07:00:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:00:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:00:05 INFO - 'tests': ['a11yr', 07:00:05 INFO - 'ts_paint', 07:00:05 INFO - 'tpaint', 07:00:05 INFO - 'sessionrestore', 07:00:05 INFO - 'sessionrestore_no_auto_restore', 07:00:05 INFO - 'tabpaint']}, 07:00:05 INFO - 'other-e10s': {'tests': ['a11yr', 07:00:05 INFO - 'ts_paint', 07:00:05 INFO - 'tpaint', 07:00:05 INFO - 'sessionrestore', 07:00:05 INFO - 'sessionrestore_no_auto_restore', 07:00:05 INFO - 'tabpaint']}, 07:00:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:00:05 INFO - 'ts_paint', 07:00:05 INFO - 'tpaint', 07:00:05 INFO - 'sessionrestore', 07:00:05 INFO - 'sessionrestore_no_auto_restore', 07:00:05 INFO - 'tabpaint']}, 07:00:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:00:05 INFO - 'ts_paint', 07:00:05 INFO - 'tpaint', 07:00:05 INFO - 'sessionrestore', 07:00:05 INFO - 'sessionrestore_no_auto_restore', 07:00:05 INFO - 'tabpaint']}, 07:00:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:00:05 INFO - 'tests': ['a11yr', 07:00:05 INFO - 'ts_paint', 07:00:05 INFO - 'tpaint', 07:00:05 INFO - 'sessionrestore', 07:00:05 INFO - 'sessionrestore_no_auto_restore', 07:00:05 INFO - 'tabpaint']}, 07:00:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:00:05 INFO - 'tests': ['a11yr', 07:00:05 INFO - 'ts_paint', 07:00:05 INFO - 'tpaint', 07:00:05 INFO - 'sessionrestore', 07:00:05 INFO - 'sessionrestore_no_auto_restore', 07:00:05 INFO - 'tabpaint']}, 07:00:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:00:05 INFO - 'tests': ['tsvgx', 07:00:05 INFO - 'tsvgr_opacity', 07:00:05 INFO - 'tart', 07:00:05 INFO - 'tscrollx', 07:00:05 INFO - 'cart']}, 07:00:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:00:05 INFO - 'tsvgr_opacity', 07:00:05 INFO - 'tart', 07:00:05 INFO - 'tscrollx', 07:00:05 INFO - 'cart']}, 07:00:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:05 INFO - 'talos_options': ['--disable-e10s'], 07:00:05 INFO - 'tests': ['tp5o']}, 07:00:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:05 INFO - 'tests': ['tp5o']}, 07:00:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:05 INFO - 'talos_options': ['--disable-e10s', 07:00:05 INFO - '--xperf_path', 07:00:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:05 INFO - 'tests': ['tp5n']}, 07:00:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:05 INFO - 'talos_options': ['--xperf_path', 07:00:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:05 INFO - 'tests': ['tp5n']}}, 07:00:05 INFO - 'talos.zip': {'path': '', 07:00:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:00:05 INFO - Running post-action listener: _resource_record_post_action 07:00:05 INFO - [mozharness: 2016-09-01 14:00:05.660055Z] Finished populate-webroot step (success) 07:00:05 INFO - [mozharness: 2016-09-01 14:00:05.660145Z] Running create-virtualenv step. 07:00:05 INFO - Running pre-action listener: _resource_record_pre_action 07:00:05 INFO - Running main action method: create_virtualenv 07:00:05 INFO - Creating virtualenv /builds/slave/test/build/venv 07:00:05 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 07:00:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:00:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:00:05 INFO - Using real prefix '/usr' 07:00:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:00:06 INFO - Installing distribute.............................................................................................................................................................................................done. 07:00:08 INFO - Installing pip.................done. 07:00:08 INFO - Return code: 0 07:00:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:00:08 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')]} 07:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:08 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')]} 07:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:08 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 0x1102030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c13b0>, '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 0x13c22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', '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 07:00:08 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 07:00:08 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 07:00:08 INFO - Using env: {'DISPLAY': ':0', 07:00:08 INFO - 'HOME': '/home/cltbld', 07:00:08 INFO - 'LANG': 'en_US.UTF-8', 07:00:08 INFO - 'LANGUAGE': 'en_US:en', 07:00:08 INFO - 'LOGNAME': 'cltbld', 07:00:08 INFO - 'MAIL': '/var/mail/cltbld', 07:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:08 INFO - 'MOZ_NO_REMOTE': '1', 07:00:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:08 INFO - 'NO_EM_RESTART': '1', 07:00:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:08 INFO - 'PWD': '/builds/slave/test', 07:00:08 INFO - 'SHELL': '/bin/bash', 07:00:08 INFO - 'SHLVL': '1', 07:00:08 INFO - 'TERM': 'linux', 07:00:08 INFO - 'TMOUT': '86400', 07:00:08 INFO - 'USER': 'cltbld', 07:00:08 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', 07:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:08 INFO - '_': '/tools/buildbot/bin/python'} 07:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:08 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 07:00:08 INFO - Cleaning up... 07:00:08 INFO - Return code: 0 07:00:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:00:08 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')]} 07:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:08 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')]} 07:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:08 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 0x1102030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c13b0>, '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 0x13c22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', '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 07:00:08 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 07:00:08 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 07:00:08 INFO - Using env: {'DISPLAY': ':0', 07:00:08 INFO - 'HOME': '/home/cltbld', 07:00:08 INFO - 'LANG': 'en_US.UTF-8', 07:00:08 INFO - 'LANGUAGE': 'en_US:en', 07:00:08 INFO - 'LOGNAME': 'cltbld', 07:00:08 INFO - 'MAIL': '/var/mail/cltbld', 07:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:08 INFO - 'MOZ_NO_REMOTE': '1', 07:00:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:08 INFO - 'NO_EM_RESTART': '1', 07:00:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:08 INFO - 'PWD': '/builds/slave/test', 07:00:08 INFO - 'SHELL': '/bin/bash', 07:00:08 INFO - 'SHLVL': '1', 07:00:08 INFO - 'TERM': 'linux', 07:00:08 INFO - 'TMOUT': '86400', 07:00:08 INFO - 'USER': 'cltbld', 07:00:08 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', 07:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:08 INFO - '_': '/tools/buildbot/bin/python'} 07:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:08 INFO - Downloading/unpacking psutil>=3.1.1 07:00:08 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 07:00:08 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 07:00:08 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 07:00:08 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 07:00:08 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 07:00:08 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 07:00:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:00:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:10 INFO - Installing collected packages: psutil 07:00:10 INFO - Running setup.py install for psutil 07:00:10 INFO - building 'psutil._psutil_linux' extension 07:00:10 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 07:00:10 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 07:00:10 INFO - building 'psutil._psutil_posix' extension 07:00:10 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 07:00:11 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 07:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:11 INFO - Successfully installed psutil 07:00:11 INFO - Cleaning up... 07:00:11 INFO - Return code: 0 07:00:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:00:11 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')]} 07:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:11 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')]} 07:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:11 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 0x1102030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c13b0>, '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 0x13c22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', '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 07:00:11 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 07:00:11 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 07:00:11 INFO - Using env: {'DISPLAY': ':0', 07:00:11 INFO - 'HOME': '/home/cltbld', 07:00:11 INFO - 'LANG': 'en_US.UTF-8', 07:00:11 INFO - 'LANGUAGE': 'en_US:en', 07:00:11 INFO - 'LOGNAME': 'cltbld', 07:00:11 INFO - 'MAIL': '/var/mail/cltbld', 07:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:11 INFO - 'MOZ_NO_REMOTE': '1', 07:00:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:11 INFO - 'NO_EM_RESTART': '1', 07:00:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:11 INFO - 'PWD': '/builds/slave/test', 07:00:11 INFO - 'SHELL': '/bin/bash', 07:00:11 INFO - 'SHLVL': '1', 07:00:11 INFO - 'TERM': 'linux', 07:00:11 INFO - 'TMOUT': '86400', 07:00:11 INFO - 'USER': 'cltbld', 07:00:11 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', 07:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:11 INFO - '_': '/tools/buildbot/bin/python'} 07:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:00:11 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 07:00:11 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 07:00:11 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 07:00:11 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 07:00:11 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 07:00:11 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 07:00:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:00:13 INFO - Installing collected packages: mozsystemmonitor 07:00:13 INFO - Running setup.py install for mozsystemmonitor 07:00:13 INFO - Successfully installed mozsystemmonitor 07:00:13 INFO - Cleaning up... 07:00:13 INFO - Return code: 0 07:00:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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', '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 0x1102030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c13b0>, '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 0x13c22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', '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 07:00: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:00: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 jsonschema==2.5.1 07:00:13 INFO - Using env: {'DISPLAY': ':0', 07:00:13 INFO - 'HOME': '/home/cltbld', 07:00:13 INFO - 'LANG': 'en_US.UTF-8', 07:00:13 INFO - 'LANGUAGE': 'en_US:en', 07:00:13 INFO - 'LOGNAME': 'cltbld', 07:00:13 INFO - 'MAIL': '/var/mail/cltbld', 07:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:13 INFO - 'MOZ_NO_REMOTE': '1', 07:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:13 INFO - 'NO_EM_RESTART': '1', 07:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:13 INFO - 'PWD': '/builds/slave/test', 07:00:13 INFO - 'SHELL': '/bin/bash', 07:00:13 INFO - 'SHLVL': '1', 07:00:13 INFO - 'TERM': 'linux', 07:00:13 INFO - 'TMOUT': '86400', 07:00:13 INFO - 'USER': 'cltbld', 07:00:13 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', 07:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:13 INFO - '_': '/tools/buildbot/bin/python'} 07:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:13 INFO - Downloading/unpacking jsonschema==2.5.1 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:00:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00:15 INFO - Downloading functools32-3.2.3-2.tar.gz 07:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:00:16 INFO - warning: no files found matching '*.txt' 07:00:16 INFO - no previously-included directories found matching 'build' 07:00:16 INFO - no previously-included directories found matching 'dist' 07:00:16 INFO - no previously-included directories found matching '.git*' 07:00:16 INFO - Installing collected packages: jsonschema, functools32 07:00:16 INFO - Running setup.py install for functools32 07:00:16 INFO - warning: no files found matching '*.txt' 07:00:16 INFO - no previously-included directories found matching 'build' 07:00:16 INFO - no previously-included directories found matching 'dist' 07:00:16 INFO - no previously-included directories found matching '.git*' 07:00:16 INFO - Successfully installed jsonschema functools32 07:00:16 INFO - Cleaning up... 07:00:16 INFO - Return code: 0 07:00:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:00:16 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')]} 07:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:16 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')]} 07:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:16 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 0x1102030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c13b0>, '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 0x13c22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', '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 07:00:16 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 07:00:16 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 07:00:16 INFO - Using env: {'DISPLAY': ':0', 07:00:16 INFO - 'HOME': '/home/cltbld', 07:00:16 INFO - 'LANG': 'en_US.UTF-8', 07:00:16 INFO - 'LANGUAGE': 'en_US:en', 07:00:16 INFO - 'LOGNAME': 'cltbld', 07:00:16 INFO - 'MAIL': '/var/mail/cltbld', 07:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:16 INFO - 'MOZ_NO_REMOTE': '1', 07:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:16 INFO - 'NO_EM_RESTART': '1', 07:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:16 INFO - 'PWD': '/builds/slave/test', 07:00:16 INFO - 'SHELL': '/bin/bash', 07:00:16 INFO - 'SHLVL': '1', 07:00:16 INFO - 'TERM': 'linux', 07:00:16 INFO - 'TMOUT': '86400', 07:00:16 INFO - 'USER': 'cltbld', 07:00:16 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', 07:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:16 INFO - '_': '/tools/buildbot/bin/python'} 07:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:00:16 INFO - Cleaning up... 07:00:16 INFO - Return code: 0 07:00:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:00:16 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')]} 07:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:16 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')]} 07:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:16 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 0x1102030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c13b0>, '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 0x13c22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', '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 07:00:16 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 07:00:16 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 07:00:16 INFO - Using env: {'DISPLAY': ':0', 07:00:16 INFO - 'HOME': '/home/cltbld', 07:00:16 INFO - 'LANG': 'en_US.UTF-8', 07:00:16 INFO - 'LANGUAGE': 'en_US:en', 07:00:16 INFO - 'LOGNAME': 'cltbld', 07:00:16 INFO - 'MAIL': '/var/mail/cltbld', 07:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:16 INFO - 'MOZ_NO_REMOTE': '1', 07:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:16 INFO - 'NO_EM_RESTART': '1', 07:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:16 INFO - 'PWD': '/builds/slave/test', 07:00:16 INFO - 'SHELL': '/bin/bash', 07:00:16 INFO - 'SHLVL': '1', 07:00:16 INFO - 'TERM': 'linux', 07:00:16 INFO - 'TMOUT': '86400', 07:00:16 INFO - 'USER': 'cltbld', 07:00:16 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', 07:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:16 INFO - '_': '/tools/buildbot/bin/python'} 07:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:16 INFO - Downloading/unpacking blobuploader==1.2.4 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:16 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 07:00:18 INFO - Downloading blobuploader-1.2.4.tar.gz 07:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:00:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:00:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 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 07:00:19 INFO - Downloading docopt-0.6.1.tar.gz 07:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:00:19 INFO - Installing collected packages: blobuploader, requests, docopt 07:00:19 INFO - Running setup.py install for blobuploader 07:00:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:00:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:00:19 INFO - Running setup.py install for requests 07:00:19 INFO - Running setup.py install for docopt 07:00:19 INFO - Successfully installed blobuploader requests docopt 07:00:19 INFO - Cleaning up... 07:00:19 INFO - Return code: 0 07:00:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:19 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')]} 07:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:19 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')]} 07:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:19 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 0x1102030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c13b0>, '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 0x13c22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', '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 07:00:19 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 07:00:19 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 07:00:19 INFO - Using env: {'DISPLAY': ':0', 07:00:19 INFO - 'HOME': '/home/cltbld', 07:00:19 INFO - 'LANG': 'en_US.UTF-8', 07:00:19 INFO - 'LANGUAGE': 'en_US:en', 07:00:19 INFO - 'LOGNAME': 'cltbld', 07:00:19 INFO - 'MAIL': '/var/mail/cltbld', 07:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:19 INFO - 'MOZ_NO_REMOTE': '1', 07:00:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:19 INFO - 'NO_EM_RESTART': '1', 07:00:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:19 INFO - 'PWD': '/builds/slave/test', 07:00:19 INFO - 'SHELL': '/bin/bash', 07:00:19 INFO - 'SHLVL': '1', 07:00:19 INFO - 'TERM': 'linux', 07:00:19 INFO - 'TMOUT': '86400', 07:00:19 INFO - 'USER': 'cltbld', 07:00:19 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', 07:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:19 INFO - '_': '/tools/buildbot/bin/python'} 07:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:20 INFO - Running setup.py (path:/tmp/pip-pU6CbU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:20 INFO - Running setup.py (path:/tmp/pip-qGxIeQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:20 INFO - Running setup.py (path:/tmp/pip-1Yag2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:20 INFO - Running setup.py (path:/tmp/pip-p0Au1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:20 INFO - Running setup.py (path:/tmp/pip-YuI1gH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:20 INFO - Running setup.py (path:/tmp/pip-tCtOE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:20 INFO - Running setup.py (path:/tmp/pip-JuNB1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:20 INFO - Running setup.py (path:/tmp/pip-MebsbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:20 INFO - Running setup.py (path:/tmp/pip-6s4zpd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:20 INFO - Running setup.py (path:/tmp/pip-_hFPuu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:20 INFO - Running setup.py (path:/tmp/pip-ZB8YrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:20 INFO - Running setup.py (path:/tmp/pip-6Wa8Yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:21 INFO - Running setup.py (path:/tmp/pip-B0X5JL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:21 INFO - Running setup.py (path:/tmp/pip-fxwQ51-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:21 INFO - Running setup.py (path:/tmp/pip-5ajY_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:21 INFO - Running setup.py (path:/tmp/pip-4BWQCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:21 INFO - Running setup.py (path:/tmp/pip-enP4vZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:00:21 INFO - Running setup.py install for manifestparser 07:00:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:00:21 INFO - Running setup.py install for mozcrash 07:00:21 INFO - Running setup.py install for mozdebug 07:00:21 INFO - Running setup.py install for mozdevice 07:00:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:00:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:00:21 INFO - Running setup.py install for mozfile 07:00:21 INFO - Running setup.py install for mozhttpd 07:00:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:00:21 INFO - Running setup.py install for mozinfo 07:00:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozInstall 07:00:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozleak 07:00:22 INFO - Running setup.py install for mozlog 07:00:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for moznetwork 07:00:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozprocess 07:00:22 INFO - Running setup.py install for mozprofile 07:00:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozrunner 07:00:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:00:22 INFO - Running setup.py install for mozscreenshot 07:00:22 INFO - Running setup.py install for moztest 07:00:23 INFO - Running setup.py install for mozversion 07:00:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:00:23 INFO - Cleaning up... 07:00:23 INFO - Return code: 0 07:00:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:23 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 0x1102030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c13b0>, '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 0x13c22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', '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 07:00:23 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 07:00:23 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 07:00:23 INFO - Using env: {'DISPLAY': ':0', 07:00:23 INFO - 'HOME': '/home/cltbld', 07:00:23 INFO - 'LANG': 'en_US.UTF-8', 07:00:23 INFO - 'LANGUAGE': 'en_US:en', 07:00:23 INFO - 'LOGNAME': 'cltbld', 07:00:23 INFO - 'MAIL': '/var/mail/cltbld', 07:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:23 INFO - 'MOZ_NO_REMOTE': '1', 07:00:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:23 INFO - 'NO_EM_RESTART': '1', 07:00:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:23 INFO - 'PWD': '/builds/slave/test', 07:00:23 INFO - 'SHELL': '/bin/bash', 07:00:23 INFO - 'SHLVL': '1', 07:00:23 INFO - 'TERM': 'linux', 07:00:23 INFO - 'TMOUT': '86400', 07:00:23 INFO - 'USER': 'cltbld', 07:00:23 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', 07:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:23 INFO - '_': '/tools/buildbot/bin/python'} 07:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:23 INFO - Running setup.py (path:/tmp/pip-k2crB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:23 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)) 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:23 INFO - Running setup.py (path:/tmp/pip-6on7wF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:23 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)) 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:23 INFO - Running setup.py (path:/tmp/pip-mjONlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:23 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)) 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:23 INFO - Running setup.py (path:/tmp/pip-zWe7wQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:23 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)) 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:23 INFO - Running setup.py (path:/tmp/pip-AxrPLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:23 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)) 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:23 INFO - Running setup.py (path:/tmp/pip-RVLfzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:23 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)) 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:23 INFO - Running setup.py (path:/tmp/pip-eShN_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:23 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)) 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:23 INFO - Running setup.py (path:/tmp/pip-qKrnuz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:23 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)) 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:23 INFO - Running setup.py (path:/tmp/pip-cuAD_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:24 INFO - Running setup.py (path:/tmp/pip-MWUljG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:24 INFO - Running setup.py (path:/tmp/pip-nVKm_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:24 INFO - Running setup.py (path:/tmp/pip-w8DbI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:24 INFO - Running setup.py (path:/tmp/pip-fKGQlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:24 INFO - Running setup.py (path:/tmp/pip-WQ4lCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:24 INFO - Running setup.py (path:/tmp/pip-drzSE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:24 INFO - Running setup.py (path:/tmp/pip-KvMxv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:24 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)) 07:00:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:24 INFO - Running setup.py (path:/tmp/pip-oxO8_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:24 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)) 07:00:24 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)) 07:00:24 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)) 07:00:24 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)) 07:00:24 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)) 07:00:24 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)) 07:00:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:00:24 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 07:00:24 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 07:00:24 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 07:00:24 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 07:00:24 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 07:00:24 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 07:00:26 INFO - Downloading blessings-1.6.tar.gz 07:00:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:00:26 INFO - Installing collected packages: blessings 07:00:26 INFO - Running setup.py install for blessings 07:00:26 INFO - Successfully installed blessings 07:00:26 INFO - Cleaning up... 07:00:26 INFO - Return code: 0 07:00:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:00:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:26 INFO - Reading from file tmpfile_stdout 07:00:26 INFO - Current package versions: 07:00:26 INFO - argparse == 1.2.1 07:00:26 INFO - blessings == 1.6 07:00:26 INFO - blobuploader == 1.2.4 07:00:26 INFO - docopt == 0.6.1 07:00:26 INFO - functools32 == 3.2.3-2 07:00:26 INFO - jsonschema == 2.5.1 07:00:26 INFO - manifestparser == 1.1 07:00:26 INFO - mozInstall == 1.12 07:00:26 INFO - mozcrash == 0.17 07:00:26 INFO - mozdebug == 0.1 07:00:26 INFO - mozdevice == 0.48 07:00:26 INFO - mozfile == 1.2 07:00:26 INFO - mozhttpd == 0.7 07:00:26 INFO - mozinfo == 0.9 07:00:26 INFO - mozleak == 0.1 07:00:26 INFO - mozlog == 3.3 07:00:26 INFO - moznetwork == 0.27 07:00:26 INFO - mozprocess == 0.23 07:00:26 INFO - mozprofile == 0.28 07:00:26 INFO - mozrunner == 6.12 07:00:26 INFO - mozscreenshot == 0.1 07:00:26 INFO - mozsystemmonitor == 0.3 07:00:26 INFO - moztest == 0.7 07:00:26 INFO - mozversion == 1.4 07:00:26 INFO - psutil == 3.1.1 07:00:26 INFO - requests == 1.2.3 07:00:26 INFO - wsgiref == 0.1.2 07:00:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:26 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')]} 07:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:26 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')]} 07:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:26 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 0x1102030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c13b0>, '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 0x13c22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', '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 07:00:26 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 07:00:26 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 07:00:26 INFO - Using env: {'DISPLAY': ':0', 07:00:26 INFO - 'HOME': '/home/cltbld', 07:00:26 INFO - 'LANG': 'en_US.UTF-8', 07:00:26 INFO - 'LANGUAGE': 'en_US:en', 07:00:26 INFO - 'LOGNAME': 'cltbld', 07:00:26 INFO - 'MAIL': '/var/mail/cltbld', 07:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:26 INFO - 'MOZ_NO_REMOTE': '1', 07:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:26 INFO - 'NO_EM_RESTART': '1', 07:00:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:26 INFO - 'PWD': '/builds/slave/test', 07:00:26 INFO - 'SHELL': '/bin/bash', 07:00:26 INFO - 'SHLVL': '1', 07:00:26 INFO - 'TERM': 'linux', 07:00:26 INFO - 'TMOUT': '86400', 07:00:26 INFO - 'USER': 'cltbld', 07:00:26 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', 07:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:26 INFO - '_': '/tools/buildbot/bin/python'} 07:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 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)) 07:00:27 INFO - Cleaning up... 07:00:27 INFO - Return code: 0 07:00:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1102030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12658a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c3180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c13b0>, '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 0x13c22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c2740>, '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': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', '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 07:00:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:00:27 INFO - Using env: {'DISPLAY': ':0', 07:00:27 INFO - 'HOME': '/home/cltbld', 07:00:27 INFO - 'LANG': 'en_US.UTF-8', 07:00:27 INFO - 'LANGUAGE': 'en_US:en', 07:00:27 INFO - 'LOGNAME': 'cltbld', 07:00:27 INFO - 'MAIL': '/var/mail/cltbld', 07:00:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:27 INFO - 'MOZ_NO_REMOTE': '1', 07:00:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:27 INFO - 'NO_EM_RESTART': '1', 07:00:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:27 INFO - 'PWD': '/builds/slave/test', 07:00:27 INFO - 'SHELL': '/bin/bash', 07:00:27 INFO - 'SHLVL': '1', 07:00:27 INFO - 'TERM': 'linux', 07:00:27 INFO - 'TMOUT': '86400', 07:00:27 INFO - 'USER': 'cltbld', 07:00:27 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', 07:00:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:27 INFO - '_': '/tools/buildbot/bin/python'} 07:00:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:00:27 INFO - Cleaning up... 07:00:27 INFO - Return code: 0 07:00:27 INFO - Running post-action listener: _resource_record_post_action 07:00:27 INFO - Running post-action listener: _start_resource_monitoring 07:00:27 INFO - Starting resource monitoring. 07:00:27 INFO - [mozharness: 2016-09-01 14:00:27.422346Z] Finished create-virtualenv step (success) 07:00:27 INFO - [mozharness: 2016-09-01 14:00:27.422772Z] Running install step. 07:00:27 INFO - Running pre-action listener: _resource_record_pre_action 07:00:27 INFO - Running main action method: install 07:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:27 INFO - Reading from file tmpfile_stdout 07:00:27 INFO - Detecting whether we're running mozinstall >=1.0... 07:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:00:27 INFO - Reading from file tmpfile_stdout 07:00:27 INFO - Output received: 07:00:27 INFO - Usage: mozinstall [options] installer 07:00:27 INFO - Options: 07:00:27 INFO - -h, --help show this help message and exit 07:00:27 INFO - -d DEST, --destination=DEST 07:00:27 INFO - Directory to install application into. [default: 07:00:27 INFO - "/builds/slave/test"] 07:00:27 INFO - --app=APP Application being installed. [default: firefox] 07:00:27 INFO - mkdir: /builds/slave/test/build/application 07:00:27 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'] 07:00:27 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 07:00:44 INFO - Reading from file tmpfile_stdout 07:00:44 INFO - Output received: 07:00:44 INFO - /builds/slave/test/build/application/firefox/firefox 07:00:44 INFO - Running post-action listener: _resource_record_post_action 07:00:44 INFO - [mozharness: 2016-09-01 14:00:44.570872Z] Finished install step (success) 07:00:44 INFO - [mozharness: 2016-09-01 14:00:44.571148Z] Running run-tests step. 07:00:44 INFO - Running pre-action listener: _resource_record_pre_action 07:00:44 INFO - Running main action method: run_tests 07:00:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:00:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:00:44 INFO - Python 2.7.3 07:00:44 INFO - Return code: 0 07:00:44 INFO - grabbing minidump binary from tooltool 07:00:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:44 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 0x13c13b0>, '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 0x13c22b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c2740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:00:44 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 07:00:44 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 07:00:44 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 07:00:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:00:44 INFO - Return code: 0 07:00:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:00:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:00:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:00:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:00:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:00:44 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/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 07:00:44 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/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --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 07:00:44 INFO - Using env: {'DISPLAY': ':0', 07:00:44 INFO - 'HOME': '/home/cltbld', 07:00:44 INFO - 'LANG': 'en_US.UTF-8', 07:00:44 INFO - 'LANGUAGE': 'en_US:en', 07:00:44 INFO - 'LOGNAME': 'cltbld', 07:00:44 INFO - 'MAIL': '/var/mail/cltbld', 07:00:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:00:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:00:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:44 INFO - 'MOZ_NO_REMOTE': '1', 07:00:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:00:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:44 INFO - 'NO_EM_RESTART': '1', 07:00:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:44 INFO - 'PWD': '/builds/slave/test', 07:00:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:00:44 INFO - 'SHELL': '/bin/bash', 07:00:44 INFO - 'SHLVL': '1', 07:00:44 INFO - 'TERM': 'linux', 07:00:44 INFO - 'TMOUT': '86400', 07:00:44 INFO - 'USER': 'cltbld', 07:00:44 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689', 07:00:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:44 INFO - '_': '/tools/buildbot/bin/python'} 07:00:44 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/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--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 07:00:44 INFO - mozversion application_buildid: 20160830104522 07:00:44 INFO - mozversion application_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:44 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:00:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:00:44 INFO - mozversion application_name: Firefox 07:00:44 INFO - mozversion application_remotingname: firefox-dev 07:00:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:44 INFO - mozversion application_vendor: Mozilla 07:00:44 INFO - mozversion application_version: 50.0a2 07:00:44 INFO - mozversion platform_buildid: 20160830104522 07:00:44 INFO - mozversion platform_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:44 INFO - mozversion platform_version: 50.0a2 07:00:44 INFO - using testdate: 1472738444 07:00:44 INFO - actual date: 1472738444 07:00:44 INFO - starting webserver on 'localhost:55896' 07:00:44 INFO - SUITE-START | Running 2 tests 07:00:44 INFO - TEST-START | dromaeo_css 07:00:44 INFO - operating with platform_type : linux_ 07:00:44 INFO - Initialising browser for dromaeo_css test... 07:00:44 INFO - TEST-INFO | started process 21655 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4btd1t/profile http://localhost:55896/getInfo.html) 07:00:48 INFO - PROCESS | 21655 | __metrics Screen width/height:1600/1200 07:00:48 INFO - PROCESS | 21655 | colorDepth:24 07:00:48 INFO - PROCESS | 21655 | Browser inner width/height: 1024/697 07:00:48 INFO - PROCESS | 21655 | __metrics 07:00:49 INFO - PROCESS | 21655 | 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" 07:00:49 INFO - PROCESS | 21655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:00:50 INFO - TEST-INFO | 21655: exit 0 07:00:50 INFO - Browser initialized. 07:00:50 INFO - Running cycle 1/1 for dromaeo_css test... 07:00:50 INFO - TEST-INFO | started process 21741 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4btd1t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:01:02 INFO - PROCESS | 21741 | RSS: Main: 145006592 07:01:02 INFO - PROCESS | 21741 | 07:01:53 INFO - PROCESS | 21741 | 1472738513246 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] 07:04:02 INFO - PROCESS | 21741 | Cycle 1(1): loaded http://localhost:55896/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55896/tests/dromaeo/cssquery-ext.html) 07:04:03 INFO - PROCESS | 21741 | RSS: Main: 225976320 07:04:03 INFO - PROCESS | 21741 | 07:07:05 INFO - PROCESS | 21741 | Cycle 1(1): loaded http://localhost:55896/tests/dromaeo/cssquery-ext.html (next: http://localhost:55896/tests/dromaeo/cssquery-jquery.html) 07:07:06 INFO - PROCESS | 21741 | RSS: Main: 244117504 07:07:06 INFO - PROCESS | 21741 | 07:10:08 INFO - PROCESS | 21741 | Cycle 1(1): loaded http://localhost:55896/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55896/tests/dromaeo/cssquery-mootools.html) 07:10:09 INFO - PROCESS | 21741 | RSS: Main: 176230400 07:10:09 INFO - PROCESS | 21741 | 07:13:10 INFO - PROCESS | 21741 | Cycle 1(1): loaded http://localhost:55896/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55896/tests/dromaeo/cssquery-prototype.html) 07:13:11 INFO - PROCESS | 21741 | RSS: Main: 222134272 07:13:11 INFO - PROCESS | 21741 | 07:16:14 INFO - PROCESS | 21741 | Cycle 1(1): loaded http://localhost:55896/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55896/tests/dromaeo/cssquery-yui.html) 07:16:14 INFO - PROCESS | 21741 | RSS: Main: 178028544 07:16:14 INFO - PROCESS | 21741 | 07:19:17 INFO - PROCESS | 21741 | Cycle 1(1): loaded http://localhost:55896/tests/dromaeo/cssquery-yui.html (next: http://localhost:55896/tests/dromaeo/cssquery-dojo.html) 07:19:17 INFO - PROCESS | 21741 | RSS: Main: 254222336 07:19:17 INFO - PROCESS | 21741 | 07:19:17 INFO - PROCESS | 21741 | __start_tp_report 07:19:17 INFO - PROCESS | 21741 | _x_x_mozilla_page_load 07:19:17 INFO - PROCESS | 21741 | _x_x_mozilla_page_load_details 07:19:17 INFO - PROCESS | 21741 | |i|pagename|runs| 07:19:17 INFO - PROCESS | 21741 | |0;dojo.html;3212.0524445288643;3215.7909735867165;3231.676832316767;3246.0748686624315;3269.4768836986086;5574.414686457919;5681.687507187114;5715.342735585398;5724.62789918655;5737.024705800013;5580.4698553637445;5660.773569057243;5663;5680.517156041741;5691.174779656947;5089.643724939256;5118.7952481900775;5146.099432599299;5154.974225128869;5208.583313334943;5221.530062294392;5279.340082489689;5283.524482796547;5285.312909321783;5289.312389389374;5093.745312734349;5151.201563757623;5159.742012899359;5174.974125129369;5190.740462976855;5257.842264732064;5270.288511050997;5272.261883336336;5286.735663216824;5308.867278318035;5086.21163719624;5128;5173.948260517384;5180.585553155738;5185.766640501187;5498.972505137449;5516.558675305966;5551.944480555183;5591.02157122506;5604.887902241932;6031.788887388921;6037.9396206038255;6041.577089603729;6044.395560443964;6046.365131661156;6031.638101713911;6066.545009124334;6069;6077.878442431171;6148.047052706793;5713.74288157034;5750.223719797816;5753.712314384347;5769.221155144086;5773.134029895549;6083.968742363814;6301.0863424804065;6307.999999999908;6308.337624549448;6308.905366419507;5885.970570147122;5895.734691938873;5921.82234532956;6018.548608854353;6019.337872833985;6031.276246850406;6034;6037.54718396122;6046.818595442144;6048.727807248684;5862.443067908542;6034.668093254825;6038.365971573009;6039.1545183674325;6039.516838652898;5993.250843644544;6037.72830222641;6053.27360716708;6120.721420533169;6135.416406698499;4670.579647831712;4696.694714843524;4702;4721.220998535203;4730.763461826895;4137.2552940471005;4169.833206671759;4191.371294305817;4202.873913782591;4215.831366745358;5772.422757724194;5782.826515204551;5803.361630220672;5863.17915491823;5902.468777810018;6145.907811382833;6147.047207682816;6152.046432802923;6156.722947467375;6161.907571386433;5694.886102278014;5837.9124313135335;5842.707864606753;5856.970715146482;5882.323532793784;5178.637495375363;5185.403678576937;5229.764660590283;5287.20691896211;5323.600729945269;6206.0690896365;6238.563300569007;6245.843853903689;6255.624662520172;6256.092866534327;5951.137085122719;6115.449609534985;6124.050772130327;6132.264128304453;6134.693265336626;6154.5384096192065;6212.81361559154;6239.563230573816;6345.17512723343;6358.936410635834;6017.338092809699;6019.637048365344;6025.66858822769;6026.668533230714;6040.214772079602;5946.43508866657;6123.4488895999575;6126.969365153146;6131.509479241561;6134.079888016655;5823.097419899922;5856.150858125463;5862.736176872137;5914.201582786441;5970.731317090828;5890.175375447527;5925.555583331354;5925.703714814328;5936.940630593639;5943.078822782476;5784.768609255582;5861.267341582302;5899.616524925866;5914.112883067574;5917.970410147922;5937.970310148422;5968;5968.552358573038;6088.238970128734;6095.786647467317;5891.764329426775;5893.174955506147;5893.970530147321;5901.439363260655;5907.763689452374 07:19:17 INFO - PROCESS | 21741 | |1;ext.html;616.1527899138681;626.8150895485832;654.4110300729343;663.7975417497661;668.896321070234;34190.48714269285;38213.61786382135;38405.61594384055;47802.282965755454;48504.757476212566;49091.75454122733;49370.753146234216;49439.50560494394;49558;49626.50373496264;9310.72067837966;9445.622175112987;9452.763680907998;9461.432314061178;9462.290328225376;8716.433431826943;11140.49867755953;11379.032782213522;11390.202685811997;11793;11415.885841141608;11488.827667584992;11503.884961150365;11504.424778761071;11506.539738410453;8717.041125476193;11318.660440186808;11359.204855660108;11381;11394.575678040244;10927.617533386294;11254.43727813606;11280.041196498225;11609.4195290236;11618.709532261677;45758.31362529565;46130.308045379345;46141.30788038182;46165.07669846584;46264.30603540949;3840.8079596020366;4123.711340206187;4162.209180255742;4203.558626344251;4427.358033085185;35678.28643427143;35712.46431303533;36064;36966;37026.62973370286;4577.908441831145;4649.232876575361;4764.904701905977;4776.928346074812;4906.852794416173;397974;399557;400199;400200;401579.9999999971;38388.61611383851;68379.65810171017;68819;68956.31043689599;69135;39023.80488097598;40953.79523102365;40992.59007409948;41122.794386027876;41187;17709.20308586091;17925.282988680567;17935.192916318796;17941.551461213574;17946.730799038025;12526.498940042296;13991.370388332549;15063.472778452702;15142.848571514363;15148.772768408482;9145.542722863831;9227.815443691088;9263.397879137834;9348.345615806827;9351.672691455766;3473.3400653875683;3533.3463309287863;3539.9646003539633;3569.268299998494;3632.164602141522;1054.5149231353664;1062.2458054781036;1086.4893500054961;1089.455272363818;1219.621917205669;35613.64386356155;35858.8207058963;35953;36085.81957090198;36132.63867361345;1146.5814977533141;1154.6016624264614;1179.687382843547;1266.2465832829453;1269;18426.35507757222;18791.718124228148;18912;19006.239750410106;19019.334323298895;646553;648763;649355.0128997489;649666;650772;67466;67513.3248667507;67979.66010169918;68012;68086;1397.5318268380004;1410.5627255550812;1424.3234463629692;1498.243387089513;1516.8634822865995;30170.3965920676;36909.44635830411;36911.815440922626;37071.629283707895;37109.628903711695;56038.15942760943;56810.71594642;56861;56963.715181423824;57149.428505716074;444462;452891;454717;454912;455506;31270.843645781624;31380.37239255248;31832.36335273328;31997.200069998435;32121.35757284888;51384.48615513797;52083;52141.7392913033;52300.7384963088;52635.21047184372;43984;45716.77141614404;46179.76910115428;46282.611521654406;46459.70498781925;44777.32834007427;45989.310160348294;46471.07057858756;46581.53418465772;46980.7650961743;45004.549954500035;45846.31230531478;46138.307925380475;46290.76854615705;46312;37992.240155197294;39729.205415890945;39901.60098398979;41008.426798414155;41100.38349424701;30122.24694382658;30213.546796797626;30265.394692106474;30570;30956.380872382877 07:19:17 INFO - PROCESS | 21741 | |2;jquery.html;400.49137595254047;416.78535554189597;416.86243539631926;418.2617679795161;428.94423724915777;14980.775288370669;20475.488112797186;20868.165273389066;20874.37376878692;21714.13143474263;20618.48453788656;20628.071736771843;20643.896780516076;20645.967701614896;20864;5602.663840169583;5788.247527821388;6019.5184385249295;6061.909071363933;6078.848028799272;5782.3639399666;5967.910481342783;6034.4568988791;6056.878862422749;6058.697065146747;5918.230630018092;5940.821775346746;5943.078822782476;5945.108233764927;5952.9107063394085;5883.264591926009;5890.970545147246;5938.495227905633;5946.108083787423;5974.701264936736;5186.1442861927735;5332.653377530487;5335;5342.332208473941;5344.4922732340365;23181.188658397044;23794.64308035381;23846.76153238463;24220.757792422028;24480.877595612084;2604.804639652015;2689.811713180078;2880.5535142053013;2938.397628486155;2971.7028297170327;14702.676759091626;14907.776383354258;15058.397664093423;15602.29789659468;15967.121808300539;2066.7933206679363;2070.1822780001967;2088.6762551804495;2095.5808838232415;2148.054855863415;60366.39633603695;63477.68261158711;64369;80236.19763802346;81677;10928.890711092947;11384.487698053608;11597.710057248636;11618.76762464746;11620.709482262842;15663.138527381103;15730.449434269745;15741.44904928322;15778.368865245262;15784.368625254861;8571.52856592881;8706.47761134334;8855.778605534913;8967.686130985514;9081.137291957253;8799.648014079365;8867.157620026088;9014.501154295875;9056.184943355129;9317.11487408695;6695.866082678319;6717.0445004198;6729.3270672933295;6871.106756121672;6960.895586566206;1996.2314508913882;2025.7467816522935;2043.4074118505466;2079.9584008320053;2091.8099897655893;483.4029972983363;503.87403149212696;977.8924318324979;1039.7385686132632;1104.8784633690289;12126.939365303117;12578.433970471351;12634.368281585885;12819.230846149074;12907.870921290667;491.9065377578249;492.8028788484635;498.7979868153404;500.30457663847284;509.8037255656599;6573.638449885207;6834.897476537856;6860.794176174722;6875.449964002868;6910.7581234656545;528700;533742;536714;541278;547506;94226;95922.52038739901;96484.51757741167;96712;98500.50749746205;1075.9677209683723;1094.4035500652165;1117.402189828438;1127.6447918905683;1130.9547618095185;17928.641427171122;18184.36354727578;18393.632127357647;18700.076594813825;18855.057247137313;43041.78479107584;43262;44490;44604;45132.77433612811;61586;62483.37516624776;63058.36941630708;63177.36822631715;65024.349756501826;8821.691240806542;8939.284857211538;9032.774180645536;9093.363464557417;9166.67916622915;10399.428031458343;13350.866491334962;13636.318184090558;13697.931510342783;13800.723985520433;12032.578859739868;12046.819297710717;12425.31660758667;12507.874921250672;12594.937025315181;11868.525258989544;12226.75546489083;12374.13381063317;12444.937775311066;12646.557370491988;12129.393530323625;12265.141440099107;12284.754304914031;12411.687788677043;12587.93706031464;8897.377183597346;8967.103289671242;9024.774380640536;9140.131687489678;9214.078592140731;9452.243820494206;11874.228175168586;12459.065570082099;12520.74958500843;12579.622611321309 07:19:17 INFO - PROCESS | 21741 | |3;mootools.html;1432.8065711128995;1523.5505525869867;1571.8113826340837;1747.2923465996269;1896.3362823011948;9044.5929933153;9478;9514.286428517871;9693.951530242339;9717.659881904136;9388.812223755522;9455.196308313785;9503.239740820734;9518;9536.713898583037;2474.059857254241;2500.6749122614033;2501.0871032073346;2501.874906254689;2504.3864253257975;2446.9233537243645;2530.595104783235;2533.3033415810705;2533.429978254887;2535.4168541235526;2507.53610582042;2531.455737016541;2555.591105423133;2556.360909772557;2559.833610815291;2428.4171798768257;2447.9755202447927;2451.4974430241846;2460.409501719583;2471.851688898672;2366.1718398560365;2398.3164798032653;2402.9519409611885;2405.9037638494437;2439.926802195919;8999.550022498848;9354.485503297316;9617.134457898823;9617.759556011086;9623.663171789023;5351.491608297206;5536.944630553723;5609.439056094398;5629.71851407432;5632.746526406321;3551.982240088809;3559.4660800879815;3646.781193128421;3663.937458137137;3666.926661466756;1058.8252938265255;1081.859358283418;1088.9183311251688;1111.5942680921487;1114.3369695031442;13169.407376668072;13199.274039927801;13375.665608359772;13401.994850386163;13403.396847141901;4896.408667183108;4977.975110124498;5200.141976573898;5201.921971170435;5236.005159019774;11688.70778230551;11692.94153529227;11705.239159454779;11713.062954963583;11730.354830484235;5849.590528662953;5861.267341582302;5867.530597552186;5872;5876.206712093898;4542.841000564964;4544.136614043321;4677.6257899367965;5137.33214682089;5289.444608316148;4403.207422663887;4424.823007079745;4445.199864024442;4477.19410506112;4548.249538826057;1405.395679857671;1419.950301739434;1422.139605538655;1427.3291552970088;1440.0351764317932;1051.090806452413;1059.3061544688242;1063.680895731293;1064.7657515346611;1084.810158222308;6256.499480041586;6410.038494225903;6523.804285871432;6589.967050164719;6681.432078273304;487.1547864455167;488.05561239002645;497.2044728434548;500.66705640748495;505.9215821547666;4463.2858742601675;4646.4191976003;4664.976675116671;4685.4143232095985;4699.483056863743;11683.649490515298;12142;12143.878561214275;12146.817797733042;12151.5139394425;12130;12130.332831694164;12135.878641213474;12137.878621213675;12142.939285303517;8457;8794.956025219833;8811.911880881109;9072.727818165466;9080.772980675403;6554.311797261219;6660.167479065117;6710.094137291403;6866.141732283465;6955.269696681978;10607.363558186533;11193.66419007431;11197.496112674786;11240.943795280971;11345.149113816331;9823.607055717692;10159.23805714589;11291.830622540832;11354.432278386214;11392.601258955896;8244.958775206085;8428.4942903426;8493.660253589787;8660.610272537624;8680.78298042554;9686.418814871131;9707.320487565803;9716.805663886824;9721.270904682036;9735.464549449849;8251.546164960984;8287;8542.44474109181;8655.610497527752;8667.349948753743;8319.126491718282;8520.14798520143;8530.914690853013;8570.48577085377;8644.82710345777;7673.616319183907;7722.227777222233;7746.30283274508;7748.883766743391;7783.299503044753;5615.887682246251;5650.773969041193;5658.490735833795;5687.488126068673;5893.145493903446;5487.643303185439;5521.558275337883;5572.4148964358155;5597.356304025089;5610.270664813711 07:19:17 INFO - PROCESS | 21741 | |4;prototype.html;344.7311097344071;353.4309761284332;358.74794983474806;362.0947630922693;364.95438070241204;5553.91698618769;5569.470900264479;5569.637973531718;5570.749316280762;5584.134459158827;5208.921866172011;5290.709011004393;5390.245365648802;5424.376196737377;5517.5585953123755;4306.870793876189;4317.4819021717285;4318.114786468782;4319.568043195671;4339.479262488498;4330.112326972963;4352.867095123383;4354.869353919387;4365.454318210223;4375.453068366454;4150.315197992328;4274.781687219143;4290.635295999844;4309.418228539138;4321.092570560186;4269.295566231568;4272.6795490338345;4273.102648443831;4289.828406863721;4291.935620965688;3780.414035824424;3828.942565861514;3836.3669994450884;3837.270918525499;3843.1929294848114;5175.560077393426;5274.129768588178;5313.256144139824;5420.972895135538;5437.075697131458;4874.878128046792;4880.951190488086;4882.41411030675;4895.510448955111;4905.362302900636;5664.49019588239;5674.773009079632;5677.801276955287;5698.886022279573;5711.086226203829;3974.5627980922363;4100.282450571168;4159.750414975111;4188.727732697365;4193.245215861144;5892.499137573312;5902.61632993854;5921.822345329647;5927.673977931212;5940.198073260141;4233.534311225762;4236.894077648022;4267.765272909957;4288.721233119837;4296.334068219431;4171.728837625545;4267.5732426757695;4293.724326107168;4340;4340.587644173798;4849.2726091085915;4894.1679914414635;4895.7062576245535;4905;4909.214525675875;5771;5804.839032193494;5817;5823.126531020296;5826.359100498942;1412.6256542016374;1432.5129455984807;1451.7096580683906;1458.8103546538882;1480.29685899197;928.4568527411404;936.7681498829018;949.9550494456181;951.5194826612517;953.9284553658504;4926.852194434173;4957.727324997088;4975.875603109951;4988.975055124701;5008.874778130576;5171.224316352502;5182.404023537341;5183.870403239949;5238.345206849144;5238.92141617876;4883.3651625288485;4935.531124543163;4966.311454105621;4987.650864439454;5242.898991211812;3104.410162069245;3126.937461250762;3130.906072817819;3138.3095718941795;3138.827364494929;6121.265448146251;6121.663308517986;6134.006447904449;6169.383061693795;6185.907211391833;5872.941270587325;5922.690150841176;5941.197938278277;5982.4316689914385;6002.579819412599;6051.546134039964;6071;6093.360197179321;6118.632882027182;6120.540959427972;5586.301712285965;5906.995810712275;5908.970455147697;5916.911246331395;5919.792807251725;5863.648181109147;5865.912011319919;5870.9432302186015;5880.26496687914;5883.176355310175;6135.085834228706;6147.754089836357;6171.580536476546;6181.53638477125;6182.165407669907;4056.411820286101;4089.2026054919006;4194;4247.171801498678;4295.119500502327;5854.209681693087;5914.615549989237;5945.346011938596;5951.851203719942;6020.458158765732;5749.453801888814;5793.275840519935;5793.594448388572;5814.476697097281;5817.447342502455;5604.523615492729;5709.885802284014;5744.253247078021;5761.91357129635;5786.247787787561;5739.5695322850115;5751.1373294006235;5760.510356619734;5779.786244888621;5789.899919015374;5590;5761.942380576141;5808.82573522795;5842.941570584239;5844.444777746107;5602.5517958562405;5627.212190293444;5627.971860140673;5649.237352957298;5651.858703532444 07:19:17 INFO - PROCESS | 21741 | |5;yui.html;805.7784109369923;828.9129641387655;834.8872902158208;875.3391189651812;896.2471523919907;16812;18106.81893181071;18113.366032188875;18165.182566784493;18166.72749908751;14524.709505809877;14539.78190327146;14562.927185364058;14586.489472868452;14612.050216735905;731.162818572734;873.4235404632911;881.0264657553407;882.2104216726059;901.324006994754;878.4553576782404;882.6116508736168;886.3352485635774;887.3877024852864;887.6626881784913;961.9759506012371;964.9035096490366;966.1739212972877;966.1787480641469;966.6230170233614;763.7441457111846;912.0605776050678;940.050548945563;946.1342871272777;951.172479942451;700.9754658586976;704.2745971649209;704.4294121761338;704.9471289653245;705.3193668110298;15451.381944722198;15508.612284692974;15520.92239538795;15522.611934701723;15525;15475.767863482057;15590.532284031497;15683.215839208107;15765;16033.198340082949;14072.788908166283;14099.859001409957;14211.624975712826;14225.78661320081;14390.928045359811;4686.929696054528;4765;4776.832810851603;4781.784819683123;4790;67242;68473.97289040669;69403;82783.5860820698;82942;12366.319852408024;12565.93717031409;12858.228506289652;12912.548060817826;13035;22974.65538016931;22984.195553155558;22985.655215171784;22990.540189196123;22995.195168169033;9368.906310936803;9396.43245012418;9544.475053872104;9643.855342169873;9813.264005199639;5034.84895453137;5043.041822053798;5067.974660126676;5175.404828444702;5395.487428694268;3541.203229273393;3669.798161101165;3681.2269423420844;3682.558093028854;3687.2994131115;601.8044135655929;668.7793028300649;669.5647828911247;677.1366507702719;695.8956156576554;714.3677845107118;754.97357592484;784.7371130671175;803.2088392932957;839.5298632765549;10410.167186625053;10595;11200.943995279971;11252.43737813106;11273.379964101889;813.1299509524752;823.5799742131557;827.230675471817;828.0808302783905;829.4484168028314;4836.322914791932;5123.692578445305;5133.717645529532;5160.509751573594;5167.483251674803;108908;126760;129315;132156.33921830522;135925;63448.68275658592;100472.49763751135;112783;114346;116476.41761791306;92946;93360;93707;94122;95755.56366654506;9609.855852162083;9684.75788105303;9906.702798916043;9932.602695892083;9935.354201976848;54912.725436372566;56334;56796;57300.42699572951;58395;78525;86044.56977715321;86576.13423865681;87172.1282787164;91089.54455227946;12447.626571202878;12450.688732781753;12765.87234127684;12862.935685321514;13030.4136313868;15289.847101529287;15499.922500387876;16156.434524791575;16435.26041328119;17219.741703874202;14146.646333841736;15150.696986059997;15395.230238487808;16044.759328609847;16608.916955415145;14024.579262622137;14508.34712437964;14553.19957402353;15175.848241517444;15647.68704625924;14667.779983300472;15013.324400401789;15197.620059498158;16192.271347789614;16224.18879056066;11678.766424671288;12809.10336276489;12876.484940602646;13000.739985200433;13645.249511276976;13012.958963283105;13324.866751332362;13364.732705346034;13562.321883905568;13573.921659422303 07:19:17 INFO - PROCESS | 21741 | __end_tp_report 07:19:17 INFO - PROCESS | 21741 | __start_cc_report 07:19:17 INFO - PROCESS | 21741 | _x_x_mozilla_cycle_collect,1061 07:19:17 INFO - PROCESS | 21741 | __end_cc_report 07:19:17 INFO - PROCESS | 21741 | __startTimestamp1472739557704__endTimestamp 07:19:17 INFO - PROCESS | 21741 | 07:19:17 INFO - PROCESS | 21741 | ------- Summary: start ------- 07:19:17 INFO - PROCESS | 21741 | Number of tests: 6 07:19:17 INFO - PROCESS | 21741 | 07:19:17 INFO - PROCESS | 21741 | [#0] dojo.html Cycles:165 Average:5653.25 Median:5891.76 stddev:638.27 (10.8%) stddev-sans-first:610.82 07:19:17 INFO - PROCESS | 21741 | Values: 3212.1 3215.8 3231.7 3246.1 3269.5 5574.4 5681.7 5715.3 5724.6 5737.0 5580.5 5660.8 5663.0 5680.5 5691.2 5089.6 5118.8 5146.1 5155.0 5208.6 5221.5 5279.3 5283.5 5285.3 5289.3 5093.7 5151.2 5159.7 5175.0 5190.7 5257.8 5270.3 5272.3 5286.7 5308.9 5086.2 5128.0 5173.9 5180.6 5185.8 5499.0 5516.6 5551.9 5591.0 5604.9 6031.8 6037.9 6041.6 6044.4 6046.4 6031.6 6066.5 6069.0 6077.9 6148.0 5713.7 5750.2 5753.7 5769.2 5773.1 6084.0 6301.1 6308.0 6308.3 6308.9 5886.0 5895.7 5921.8 6018.5 6019.3 6031.3 6034.0 6037.5 6046.8 6048.7 5862.4 6034.7 6038.4 6039.2 6039.5 5993.3 6037.7 6053.3 6120.7 6135.4 4670.6 4696.7 4702.0 4721.2 4730.8 4137.3 4169.8 4191.4 4202.9 4215.8 5772.4 5782.8 5803.4 5863.2 5902.5 6145.9 6147.0 6152.0 6156.7 6161.9 5694.9 5837.9 5842.7 5857.0 5882.3 5178.6 5185.4 5229.8 5287.2 5323.6 6206.1 6238.6 6245.8 6255.6 6256.1 5951.1 6115.4 6124.1 6132.3 6134.7 6154.5 6212.8 6239.6 6345.2 6358.9 6017.3 6019.6 6025.7 6026.7 6040.2 5946.4 6123.4 6127.0 6131.5 6134.1 5823.1 5856.2 5862.7 5914.2 5970.7 5890.2 5925.6 5925.7 5936.9 5943.1 5784.8 5861.3 5899.6 5914.1 5918.0 5938.0 5968.0 5968.6 6088.2 6095.8 5891.8 5893.2 5894.0 5901.4 5907.8 07:19:17 INFO - PROCESS | 21741 | 07:19:17 INFO - PROCESS | 21741 | [#1] ext.html Cycles:180 Average:66592.98 Median:400890.00 stddev:136212.45 (34.0%) stddev-sans-first:136504.49 07:19:17 INFO - PROCESS | 21741 | Values: 616.2 626.8 654.4 663.8 668.9 34190.5 38213.6 38405.6 47802.3 48504.8 49091.8 49370.8 49439.5 49558.0 49626.5 9310.7 9445.6 9452.8 9461.4 9462.3 8716.4 11140.5 11379.0 11390.2 11793.0 11415.9 11488.8 11503.9 11504.4 11506.5 8717.0 11318.7 11359.2 11381.0 11394.6 10927.6 11254.4 11280.0 11609.4 11618.7 45758.3 46130.3 46141.3 46165.1 46264.3 3840.8 4123.7 4162.2 4203.6 4427.4 35678.3 35712.5 36064.0 36966.0 37026.6 4577.9 4649.2 4764.9 4776.9 4906.9 397974.0 399557.0 400199.0 400200.0 401580.0 38388.6 68379.7 68819.0 68956.3 69135.0 39023.8 40953.8 40992.6 41122.8 41187.0 17709.2 17925.3 17935.2 17941.6 17946.7 12526.5 13991.4 15063.5 15142.8 15148.8 9145.5 9227.8 9263.4 9348.3 9351.7 3473.3 3533.3 3540.0 3569.3 3632.2 1054.5 1062.2 1086.5 1089.5 1219.6 35613.6 35858.8 35953.0 36085.8 36132.6 1146.6 1154.6 1179.7 1266.2 1269.0 18426.4 18791.7 18912.0 19006.2 19019.3 646553.0 648763.0 649355.0 649666.0 650772.0 67466.0 67513.3 67979.7 68012.0 68086.0 1397.5 1410.6 1424.3 1498.2 1516.9 30170.4 36909.4 36911.8 37071.6 37109.6 56038.2 56810.7 56861.0 56963.7 57149.4 444462.0 452891.0 454717.0 454912.0 455506.0 31270.8 31380.4 31832.4 31997.2 32121.4 51384.5 52083.0 52141.7 52300.7 52635.2 43984.0 45716.8 46179.8 46282.6 46459.7 44777.3 45989.3 46471.1 46581.5 46980.8 45004.5 45846.3 46138.3 46290.8 46312.0 37992.2 39729.2 39901.6 41008.4 41100.4 30122.2 30213.5 30265.4 30570.0 30956.4 07:19:17 INFO - PROCESS | 21741 | 07:19:17 INFO - PROCESS | 21741 | [#2] jquery.html Cycles:180 Average:30529.76 Median:21735.36 stddev:88368.47 (406.6%) stddev-sans-first:88587.41 07:19:17 INFO - PROCESS | 21741 | Values: 400.5 416.8 416.9 418.3 428.9 14980.8 20475.5 20868.2 20874.4 21714.1 20618.5 20628.1 20643.9 20646.0 20864.0 5602.7 5788.2 6019.5 6061.9 6078.8 5782.4 5967.9 6034.5 6056.9 6058.7 5918.2 5940.8 5943.1 5945.1 5952.9 5883.3 5891.0 5938.5 5946.1 5974.7 5186.1 5332.7 5335.0 5342.3 5344.5 23181.2 23794.6 23846.8 24220.8 24480.9 2604.8 2689.8 2880.6 2938.4 2971.7 14702.7 14907.8 15058.4 15602.3 15967.1 2066.8 2070.2 2088.7 2095.6 2148.1 60366.4 63477.7 64369.0 80236.2 81677.0 10928.9 11384.5 11597.7 11618.8 11620.7 15663.1 15730.4 15741.4 15778.4 15784.4 8571.5 8706.5 8855.8 8967.7 9081.1 8799.6 8867.2 9014.5 9056.2 9317.1 6695.9 6717.0 6729.3 6871.1 6960.9 1996.2 2025.7 2043.4 2080.0 2091.8 483.4 503.9 977.9 1039.7 1104.9 12126.9 12578.4 12634.4 12819.2 12907.9 491.9 492.8 498.8 500.3 509.8 6573.6 6834.9 6860.8 6875.4 6910.8 528700.0 533742.0 536714.0 541278.0 547506.0 94226.0 95922.5 96484.5 96712.0 98500.5 1076.0 1094.4 1117.4 1127.6 1131.0 17928.6 18184.4 18393.6 18700.1 18855.1 43041.8 43262.0 44490.0 44604.0 45132.8 61586.0 62483.4 63058.4 63177.4 65024.3 8821.7 8939.3 9032.8 9093.4 9166.7 10399.4 13350.9 13636.3 13697.9 13800.7 12032.6 12046.8 12425.3 12507.9 12594.9 11868.5 12226.8 12374.1 12444.9 12646.6 12129.4 12265.1 12284.8 12411.7 12587.9 8897.4 8967.1 9024.8 9140.1 9214.1 9452.2 11874.2 12459.1 12520.7 12579.6 07:19:17 INFO - PROCESS | 21741 | 07:19:17 INFO - PROCESS | 21741 | [#3] mootools.html Cycles:180 Average:6333.79 Median:4692.45 stddev:3663.15 (78.1%) stddev-sans-first:3654.91 07:19:17 INFO - PROCESS | 21741 | Values: 1432.8 1523.6 1571.8 1747.3 1896.3 9044.6 9478.0 9514.3 9694.0 9717.7 9388.8 9455.2 9503.2 9518.0 9536.7 2474.1 2500.7 2501.1 2501.9 2504.4 2446.9 2530.6 2533.3 2533.4 2535.4 2507.5 2531.5 2555.6 2556.4 2559.8 2428.4 2448.0 2451.5 2460.4 2471.9 2366.2 2398.3 2403.0 2405.9 2439.9 8999.6 9354.5 9617.1 9617.8 9623.7 5351.5 5536.9 5609.4 5629.7 5632.7 3552.0 3559.5 3646.8 3663.9 3666.9 1058.8 1081.9 1088.9 1111.6 1114.3 13169.4 13199.3 13375.7 13402.0 13403.4 4896.4 4978.0 5200.1 5201.9 5236.0 11688.7 11692.9 11705.2 11713.1 11730.4 5849.6 5861.3 5867.5 5872.0 5876.2 4542.8 4544.1 4677.6 5137.3 5289.4 4403.2 4424.8 4445.2 4477.2 4548.2 1405.4 1420.0 1422.1 1427.3 1440.0 1051.1 1059.3 1063.7 1064.8 1084.8 6256.5 6410.0 6523.8 6590.0 6681.4 487.2 488.1 497.2 500.7 505.9 4463.3 4646.4 4665.0 4685.4 4699.5 11683.6 12142.0 12143.9 12146.8 12151.5 12130.0 12130.3 12135.9 12137.9 12142.9 8457.0 8795.0 8811.9 9072.7 9080.8 6554.3 6660.2 6710.1 6866.1 6955.3 10607.4 11193.7 11197.5 11240.9 11345.1 9823.6 10159.2 11291.8 11354.4 11392.6 8245.0 8428.5 8493.7 8660.6 8680.8 9686.4 9707.3 9716.8 9721.3 9735.5 8251.5 8287.0 8542.4 8655.6 8667.3 8319.1 8520.1 8530.9 8570.5 8644.8 7673.6 7722.2 7746.3 7748.9 7783.3 5615.9 5650.8 5658.5 5687.5 5893.1 5487.6 5521.6 5572.4 5597.4 5610.3 07:19:17 INFO - PROCESS | 21741 | 07:19:17 INFO - PROCESS | 21741 | [#4] prototype.html Cycles:180 Average:4807.89 Median:5405.61 stddev:1409.80 (26.1%) stddev-sans-first:1373.37 07:19:17 INFO - PROCESS | 21741 | Values: 344.7 353.4 358.7 362.1 365.0 5553.9 5569.5 5569.6 5570.7 5584.1 5208.9 5290.7 5390.2 5424.4 5517.6 4306.9 4317.5 4318.1 4319.6 4339.5 4330.1 4352.9 4354.9 4365.5 4375.5 4150.3 4274.8 4290.6 4309.4 4321.1 4269.3 4272.7 4273.1 4289.8 4291.9 3780.4 3828.9 3836.4 3837.3 3843.2 5175.6 5274.1 5313.3 5421.0 5437.1 4874.9 4881.0 4882.4 4895.5 4905.4 5664.5 5674.8 5677.8 5698.9 5711.1 3974.6 4100.3 4159.8 4188.7 4193.2 5892.5 5902.6 5921.8 5927.7 5940.2 4233.5 4236.9 4267.8 4288.7 4296.3 4171.7 4267.6 4293.7 4340.0 4340.6 4849.3 4894.2 4895.7 4905.0 4909.2 5771.0 5804.8 5817.0 5823.1 5826.4 1412.6 1432.5 1451.7 1458.8 1480.3 928.5 936.8 950.0 951.5 953.9 4926.9 4957.7 4975.9 4989.0 5008.9 5171.2 5182.4 5183.9 5238.3 5238.9 4883.4 4935.5 4966.3 4987.7 5242.9 3104.4 3126.9 3130.9 3138.3 3138.8 6121.3 6121.7 6134.0 6169.4 6185.9 5872.9 5922.7 5941.2 5982.4 6002.6 6051.5 6071.0 6093.4 6118.6 6120.5 5586.3 5907.0 5909.0 5916.9 5919.8 5863.6 5865.9 5870.9 5880.3 5883.2 6135.1 6147.8 6171.6 6181.5 6182.2 4056.4 4089.2 4194.0 4247.2 4295.1 5854.2 5914.6 5945.3 5951.9 6020.5 5749.5 5793.3 5793.6 5814.5 5817.4 5604.5 5709.9 5744.3 5761.9 5786.2 5739.6 5751.1 5760.5 5779.8 5789.9 5590.0 5761.9 5808.8 5842.9 5844.4 5602.6 5627.2 5628.0 5649.2 5651.9 07:19:17 INFO - PROCESS | 21741 | 07:19:17 INFO - PROCESS | 21741 | [#5] yui.html Cycles:180 Average:22488.10 Median:4770.92 stddev:32315.16 (677.3%) stddev-sans-first:32364.80 07:19:17 INFO - PROCESS | 21741 | Values: 805.8 828.9 834.9 875.3 896.2 16812.0 18106.8 18113.4 18165.2 18166.7 14524.7 14539.8 14562.9 14586.5 14612.1 731.2 873.4 881.0 882.2 901.3 878.5 882.6 886.3 887.4 887.7 962.0 964.9 966.2 966.2 966.6 763.7 912.1 940.1 946.1 951.2 701.0 704.3 704.4 704.9 705.3 15451.4 15508.6 15520.9 15522.6 15525.0 15475.8 15590.5 15683.2 15765.0 16033.2 14072.8 14099.9 14211.6 14225.8 14390.9 4686.9 4765.0 4776.8 4781.8 4790.0 67242.0 68474.0 69403.0 82783.6 82942.0 12366.3 12565.9 12858.2 12912.5 13035.0 22974.7 22984.2 22985.7 22990.5 22995.2 9368.9 9396.4 9544.5 9643.9 9813.3 5034.8 5043.0 5068.0 5175.4 5395.5 3541.2 3669.8 3681.2 3682.6 3687.3 601.8 668.8 669.6 677.1 695.9 714.4 755.0 784.7 803.2 839.5 10410.2 10595.0 11200.9 11252.4 11273.4 813.1 823.6 827.2 828.1 829.4 4836.3 5123.7 5133.7 5160.5 5167.5 108908.0 126760.0 129315.0 132156.3 135925.0 63448.7 100472.5 112783.0 114346.0 116476.4 92946.0 93360.0 93707.0 94122.0 95755.6 9609.9 9684.8 9906.7 9932.6 9935.4 54912.7 56334.0 56796.0 57300.4 58395.0 78525.0 86044.6 86576.1 87172.1 91089.5 12447.6 12450.7 12765.9 12862.9 13030.4 15289.8 15499.9 16156.4 16435.3 17219.7 14146.6 15150.7 15395.2 16044.8 16608.9 14024.6 14508.3 14553.2 15175.8 15647.7 14667.8 15013.3 15197.6 16192.3 16224.2 11678.8 12809.1 12876.5 13000.7 13645.2 13013.0 13324.9 13364.7 13562.3 13573.9 07:19:17 INFO - PROCESS | 21741 | -------- Summary: end -------- 07:19:17 INFO - PROCESS | 21741 | 07:19:18 INFO - TEST-INFO | 21741: exit 0 07:19:18 INFO - TEST-OK | dromaeo_css | took 1113677ms 07:19:18 INFO - TEST-START | kraken 07:19:18 INFO - operating with platform_type : linux_ 07:19:18 INFO - Initialising browser for kraken test... 07:19:18 INFO - TEST-INFO | started process 21952 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEkxsbV/profile http://localhost:55896/getInfo.html) 07:19:22 INFO - PROCESS | 21952 | __metrics Screen width/height:1600/1200 07:19:22 INFO - PROCESS | 21952 | colorDepth:24 07:19:22 INFO - PROCESS | 21952 | Browser inner width/height: 1024/697 07:19:22 INFO - PROCESS | 21952 | __metrics 07:19:23 INFO - PROCESS | 21952 | 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" 07:19:23 INFO - PROCESS | 21952 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:19:24 INFO - TEST-INFO | 21952: exit 0 07:19:24 INFO - Browser initialized. 07:19:24 INFO - Running cycle 1/1 for kraken test... 07:19:24 INFO - TEST-INFO | started process 22039 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpEkxsbV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:36 INFO - PROCESS | 22039 | RSS: Main: 145858560 07:19:36 INFO - PROCESS | 22039 | 07:19:38 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55896/tests/kraken/driver.html?testName=audio-beat-detection) 07:19:39 INFO - PROCESS | 22039 | RSS: Main: 191647744 07:19:39 INFO - PROCESS | 22039 | 07:19:41 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55896/tests/kraken/driver.html?testName=audio-dft) 07:19:42 INFO - PROCESS | 22039 | RSS: Main: 181383168 07:19:42 INFO - PROCESS | 22039 | 07:19:45 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55896/tests/kraken/driver.html?testName=audio-fft) 07:19:45 INFO - PROCESS | 22039 | RSS: Main: 270434304 07:19:45 INFO - PROCESS | 22039 | 07:19:48 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55896/tests/kraken/driver.html?testName=audio-oscillator) 07:19:48 INFO - PROCESS | 22039 | RSS: Main: 175587328 07:19:48 INFO - PROCESS | 22039 | 07:19:50 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55896/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:19:51 INFO - PROCESS | 22039 | RSS: Main: 177561600 07:19:51 INFO - PROCESS | 22039 | 07:19:54 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55896/tests/kraken/driver.html?testName=imaging-darkroom) 07:19:55 INFO - PROCESS | 22039 | RSS: Main: 213135360 07:19:55 INFO - PROCESS | 22039 | 07:19:58 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55896/tests/kraken/driver.html?testName=imaging-desaturate) 07:19:58 INFO - PROCESS | 22039 | RSS: Main: 211800064 07:19:58 INFO - PROCESS | 22039 | 07:20:01 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55896/tests/kraken/driver.html?testName=json-parse-financial) 07:20:02 INFO - PROCESS | 22039 | RSS: Main: 211685376 07:20:02 INFO - PROCESS | 22039 | 07:20:03 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55896/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:20:04 INFO - PROCESS | 22039 | RSS: Main: 175665152 07:20:04 INFO - PROCESS | 22039 | 07:20:06 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55896/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:20:06 INFO - PROCESS | 22039 | RSS: Main: 180854784 07:20:06 INFO - PROCESS | 22039 | 07:20:08 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55896/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:20:09 INFO - PROCESS | 22039 | RSS: Main: 186265600 07:20:09 INFO - PROCESS | 22039 | 07:20:11 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:20:12 INFO - PROCESS | 22039 | RSS: Main: 181366784 07:20:12 INFO - PROCESS | 22039 | 07:20:15 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:20:15 INFO - PROCESS | 22039 | RSS: Main: 184680448 07:20:15 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | Cycle 1(1): loaded http://localhost:55896/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55896/tests/kraken/driver.html?testName=ai-astar) 07:20:17 INFO - PROCESS | 22039 | RSS: Main: 235282432 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | __start_tp_report 07:20:17 INFO - PROCESS | 22039 | _x_x_mozilla_page_load 07:20:17 INFO - PROCESS | 22039 | _x_x_mozilla_page_load_details 07:20:17 INFO - PROCESS | 22039 | |i|pagename|runs| 07:20:17 INFO - PROCESS | 22039 | |0;ai-astar;100;110;111;110;112;109;109;111;109;110 07:20:17 INFO - PROCESS | 22039 | |1;audio-beat-detection;141;118;115;167;119;116;151;119;145;120 07:20:17 INFO - PROCESS | 22039 | |2;audio-dft;160;160;160;161;159;159;160;160;160;159 07:20:17 INFO - PROCESS | 22039 | |3;audio-fft;74;75;73;123;75;76;76;76;115;76 07:20:17 INFO - PROCESS | 22039 | |4;audio-oscillator;83;83;128;83;84;83;127;83;83;124 07:20:17 INFO - PROCESS | 22039 | |5;imaging-gaussian-blur;117;117;117;117;117;116;116;116;117;116 07:20:17 INFO - PROCESS | 22039 | |6;imaging-darkroom;145;145;147;146;146;146;146;146;146;146 07:20:17 INFO - PROCESS | 22039 | |7;imaging-desaturate;95;95;94;95;94;95;95;94;95;94 07:20:17 INFO - PROCESS | 22039 | |8;json-parse-financial;50;50;50;51;50;51;52;51;50;49 07:20:17 INFO - PROCESS | 22039 | |9;json-stringify-tinderbox;62;60;60;60;61;60;61;59;60;61 07:20:17 INFO - PROCESS | 22039 | |10;stanford-crypto-aes;82;73;82;82;72;75;72;73;72;73 07:20:17 INFO - PROCESS | 22039 | |11;stanford-crypto-ccm;127;122;129;124;120;124;118;129;148;119 07:20:17 INFO - PROCESS | 22039 | |12;stanford-crypto-pbkdf2;150;153;155;156;150;152;155;149;151;150 07:20:17 INFO - PROCESS | 22039 | |13;stanford-crypto-sha256-iterative;62;61;62;61;61;64;61;60;61;58 07:20:17 INFO - PROCESS | 22039 | __end_tp_report 07:20:17 INFO - PROCESS | 22039 | __start_cc_report 07:20:17 INFO - PROCESS | 22039 | _x_x_mozilla_cycle_collect,1606 07:20:17 INFO - PROCESS | 22039 | __end_cc_report 07:20:17 INFO - PROCESS | 22039 | __startTimestamp1472739617627__endTimestamp 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | ------- Summary: start ------- 07:20:17 INFO - PROCESS | 22039 | Number of tests: 14 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#0] ai-astar Cycles:10 Average:109.10 Median:110.00 stddev:3.35 (3.0%) stddev-sans-first:1.05 07:20:17 INFO - PROCESS | 22039 | Values: 100.0 110.0 111.0 110.0 112.0 109.0 109.0 111.0 109.0 110.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:130.50 stddev:18.41 (14.1%) stddev-sans-first:19.18 07:20:17 INFO - PROCESS | 22039 | Values: 141.0 118.0 115.0 167.0 119.0 116.0 151.0 119.0 145.0 120.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 07:20:17 INFO - PROCESS | 22039 | Values: 160.0 160.0 160.0 161.0 159.0 159.0 160.0 160.0 160.0 159.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#3] audio-fft Cycles:10 Average:83.90 Median:75.50 stddev:18.62 (24.7%) stddev-sans-first:19.40 07:20:17 INFO - PROCESS | 22039 | Values: 74.0 75.0 73.0 123.0 75.0 76.0 76.0 76.0 115.0 76.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:83.00 stddev:20.89 (25.2%) stddev-sans-first:21.61 07:20:17 INFO - PROCESS | 22039 | Values: 83.0 83.0 128.0 83.0 84.0 83.0 127.0 83.0 83.0 124.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 07:20:17 INFO - PROCESS | 22039 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 116.0 116.0 117.0 116.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.50 07:20:17 INFO - PROCESS | 22039 | Values: 145.0 145.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 07:20:17 INFO - PROCESS | 22039 | Values: 95.0 95.0 94.0 95.0 94.0 95.0 95.0 94.0 95.0 94.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#8] json-parse-financial Cycles:10 Average:50.40 Median:50.50 stddev:0.84 (1.7%) stddev-sans-first:0.88 07:20:17 INFO - PROCESS | 22039 | Values: 50.0 50.0 50.0 51.0 50.0 51.0 52.0 51.0 50.0 49.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:60.50 stddev:0.84 (1.4%) stddev-sans-first:0.67 07:20:17 INFO - PROCESS | 22039 | Values: 62.0 60.0 60.0 60.0 61.0 60.0 61.0 59.0 60.0 61.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#10] stanford-crypto-aes Cycles:10 Average:75.60 Median:74.00 stddev:4.50 (6.1%) stddev-sans-first:4.14 07:20:17 INFO - PROCESS | 22039 | Values: 82.0 73.0 82.0 82.0 72.0 75.0 72.0 73.0 72.0 73.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:125.50 stddev:8.67 (6.9%) stddev-sans-first:9.18 07:20:17 INFO - PROCESS | 22039 | Values: 127.0 122.0 129.0 124.0 120.0 124.0 118.0 129.0 148.0 119.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.10 Median:152.50 stddev:2.51 (1.6%) stddev-sans-first:2.55 07:20:17 INFO - PROCESS | 22039 | Values: 150.0 153.0 155.0 156.0 150.0 152.0 155.0 149.0 151.0 150.0 07:20:17 INFO - PROCESS | 22039 | 07:20:17 INFO - PROCESS | 22039 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.00 stddev:1.52 (2.5%) stddev-sans-first:1.58 07:20:17 INFO - PROCESS | 22039 | Values: 62.0 61.0 62.0 61.0 61.0 64.0 61.0 60.0 61.0 58.0 07:20:17 INFO - PROCESS | 22039 | -------- Summary: end -------- 07:20:17 INFO - PROCESS | 22039 | 07:20:18 INFO - TEST-INFO | 22039: exit 0 07:20:18 INFO - TEST-OK | kraken | took 60142ms 07:20:18 INFO - SUITE-END | took 1173s 07:20:18 INFO - Completed test suite (00:19:33) 07:20:18 INFO - javascript benchmark 07:20:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3212.0524445288643, 3215.7909735867165, 3231.676832316767, 3246.0748686624315, 3269.4768836986086, 5574.414686457919, 5681.687507187114, 5715.342735585398, 5724.62789918655, 5737.024705800013, 5580.4698553637445, 5660.773569057243, 5663.0, 5680.517156041741, 5691.174779656947, 5089.643724939256, 5118.7952481900775, 5146.099432599299, 5154.974225128869, 5208.583313334943, 5221.530062294392, 5279.340082489689, 5283.524482796547, 5285.312909321783, 5289.312389389374, 5093.745312734349, 5151.201563757623, 5159.742012899359, 5174.974125129369, 5190.740462976855, 5257.842264732064, 5270.288511050997, 5272.261883336336, 5286.735663216824, 5308.867278318035, 5086.21163719624, 5128.0, 5173.948260517384, 5180.585553155738, 5185.766640501187, 5498.972505137449, 5516.558675305966, 5551.944480555183, 5591.02157122506, 5604.887902241932, 6031.788887388921, 6037.9396206038255, 6041.577089603729, 6044.395560443964, 6046.365131661156, 6031.638101713911, 6066.545009124334, 6069.0, 6077.878442431171, 6148.047052706793, 5713.74288157034, 5750.223719797816, 5753.712314384347, 5769.221155144086, 5773.134029895549, 6083.968742363814, 6301.0863424804065, 6307.999999999908, 6308.337624549448, 6308.905366419507, 5885.970570147122, 5895.734691938873, 5921.82234532956, 6018.548608854353, 6019.337872833985, 6031.276246850406, 6034.0, 6037.54718396122, 6046.818595442144, 6048.727807248684, 5862.443067908542, 6034.668093254825, 6038.365971573009, 6039.1545183674325, 6039.516838652898, 5993.250843644544, 6037.72830222641, 6053.27360716708, 6120.721420533169, 6135.416406698499, 4670.579647831712, 4696.694714843524, 4702.0, 4721.220998535203, 4730.763461826895, 4137.2552940471005, 4169.833206671759, 4191.371294305817, 4202.873913782591, 4215.831366745358, 5772.422757724194, 5782.826515204551, 5803.361630220672, 5863.17915491823, 5902.468777810018, 6145.907811382833, 6147.047207682816, 6152.046432802923, 6156.722947467375, 6161.907571386433, 5694.886102278014, 5837.9124313135335, 5842.707864606753, 5856.970715146482, 5882.323532793784, 5178.637495375363, 5185.403678576937, 5229.764660590283, 5287.20691896211, 5323.600729945269, 6206.0690896365, 6238.563300569007, 6245.843853903689, 6255.624662520172, 6256.092866534327, 5951.137085122719, 6115.449609534985, 6124.050772130327, 6132.264128304453, 6134.693265336626, 6154.5384096192065, 6212.81361559154, 6239.563230573816, 6345.17512723343, 6358.936410635834, 6017.338092809699, 6019.637048365344, 6025.66858822769, 6026.668533230714, 6040.214772079602, 5946.43508866657, 6123.4488895999575, 6126.969365153146, 6131.509479241561, 6134.079888016655, 5823.097419899922, 5856.150858125463, 5862.736176872137, 5914.201582786441, 5970.731317090828, 5890.175375447527, 5925.555583331354, 5925.703714814328, 5936.940630593639, 5943.078822782476, 5784.768609255582, 5861.267341582302, 5899.616524925866, 5914.112883067574, 5917.970410147922, 5937.970310148422, 5968.0, 5968.552358573038, 6088.238970128734, 6095.786647467317, 5891.764329426775, 5893.174955506147, 5893.970530147321, 5901.439363260655, 5907.763689452374], "value": 5609.81537430668, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.1527899138681, 626.8150895485832, 654.4110300729343, 663.7975417497661, 668.896321070234, 34190.48714269285, 38213.61786382135, 38405.61594384055, 47802.282965755454, 48504.757476212566, 49091.75454122733, 49370.753146234216, 49439.50560494394, 49558.0, 49626.50373496264, 9310.72067837966, 9445.622175112987, 9452.763680907998, 9461.432314061178, 9462.290328225376, 8716.433431826943, 11140.49867755953, 11379.032782213522, 11390.202685811997, 11793.0, 11415.885841141608, 11488.827667584992, 11503.884961150365, 11504.424778761071, 11506.539738410453, 8717.041125476193, 11318.660440186808, 11359.204855660108, 11381.0, 11394.575678040244, 10927.617533386294, 11254.43727813606, 11280.041196498225, 11609.4195290236, 11618.709532261677, 45758.31362529565, 46130.308045379345, 46141.30788038182, 46165.07669846584, 46264.30603540949, 3840.8079596020366, 4123.711340206187, 4162.209180255742, 4203.558626344251, 4427.358033085185, 35678.28643427143, 35712.46431303533, 36064.0, 36966.0, 37026.62973370286, 4577.908441831145, 4649.232876575361, 4764.904701905977, 4776.928346074812, 4906.852794416173, 397974.0, 399557.0, 400199.0, 400200.0, 401579.9999999971, 38388.61611383851, 68379.65810171017, 68819.0, 68956.31043689599, 69135.0, 39023.80488097598, 40953.79523102365, 40992.59007409948, 41122.794386027876, 41187.0, 17709.20308586091, 17925.282988680567, 17935.192916318796, 17941.551461213574, 17946.730799038025, 12526.498940042296, 13991.370388332549, 15063.472778452702, 15142.848571514363, 15148.772768408482, 9145.542722863831, 9227.815443691088, 9263.397879137834, 9348.345615806827, 9351.672691455766, 3473.3400653875683, 3533.3463309287863, 3539.9646003539633, 3569.268299998494, 3632.164602141522, 1054.5149231353664, 1062.2458054781036, 1086.4893500054961, 1089.455272363818, 1219.621917205669, 35613.64386356155, 35858.8207058963, 35953.0, 36085.81957090198, 36132.63867361345, 1146.5814977533141, 1154.6016624264614, 1179.687382843547, 1266.2465832829453, 1269.0, 18426.35507757222, 18791.718124228148, 18912.0, 19006.239750410106, 19019.334323298895, 646553.0, 648763.0, 649355.0128997489, 649666.0, 650772.0, 67466.0, 67513.3248667507, 67979.66010169918, 68012.0, 68086.0, 1397.5318268380004, 1410.5627255550812, 1424.3234463629692, 1498.243387089513, 1516.8634822865995, 30170.3965920676, 36909.44635830411, 36911.815440922626, 37071.629283707895, 37109.628903711695, 56038.15942760943, 56810.71594642, 56861.0, 56963.715181423824, 57149.428505716074, 444462.0, 452891.0, 454717.0, 454912.0, 455506.0, 31270.843645781624, 31380.37239255248, 31832.36335273328, 31997.200069998435, 32121.35757284888, 51384.48615513797, 52083.0, 52141.7392913033, 52300.7384963088, 52635.21047184372, 43984.0, 45716.77141614404, 46179.76910115428, 46282.611521654406, 46459.70498781925, 44777.32834007427, 45989.310160348294, 46471.07057858756, 46581.53418465772, 46980.7650961743, 45004.549954500035, 45846.31230531478, 46138.307925380475, 46290.76854615705, 46312.0, 37992.240155197294, 39729.205415890945, 39901.60098398979, 41008.426798414155, 41100.38349424701, 30122.24694382658, 30213.546796797626, 30265.394692106474, 30570.0, 30956.380872382877], "value": 21196.352629085028, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.49137595254047, 416.78535554189597, 416.86243539631926, 418.2617679795161, 428.94423724915777, 14980.775288370669, 20475.488112797186, 20868.165273389066, 20874.37376878692, 21714.13143474263, 20618.48453788656, 20628.071736771843, 20643.896780516076, 20645.967701614896, 20864.0, 5602.663840169583, 5788.247527821388, 6019.5184385249295, 6061.909071363933, 6078.848028799272, 5782.3639399666, 5967.910481342783, 6034.4568988791, 6056.878862422749, 6058.697065146747, 5918.230630018092, 5940.821775346746, 5943.078822782476, 5945.108233764927, 5952.9107063394085, 5883.264591926009, 5890.970545147246, 5938.495227905633, 5946.108083787423, 5974.701264936736, 5186.1442861927735, 5332.653377530487, 5335.0, 5342.332208473941, 5344.4922732340365, 23181.188658397044, 23794.64308035381, 23846.76153238463, 24220.757792422028, 24480.877595612084, 2604.804639652015, 2689.811713180078, 2880.5535142053013, 2938.397628486155, 2971.7028297170327, 14702.676759091626, 14907.776383354258, 15058.397664093423, 15602.29789659468, 15967.121808300539, 2066.7933206679363, 2070.1822780001967, 2088.6762551804495, 2095.5808838232415, 2148.054855863415, 60366.39633603695, 63477.68261158711, 64369.0, 80236.19763802346, 81677.0, 10928.890711092947, 11384.487698053608, 11597.710057248636, 11618.76762464746, 11620.709482262842, 15663.138527381103, 15730.449434269745, 15741.44904928322, 15778.368865245262, 15784.368625254861, 8571.52856592881, 8706.47761134334, 8855.778605534913, 8967.686130985514, 9081.137291957253, 8799.648014079365, 8867.157620026088, 9014.501154295875, 9056.184943355129, 9317.11487408695, 6695.866082678319, 6717.0445004198, 6729.3270672933295, 6871.106756121672, 6960.895586566206, 1996.2314508913882, 2025.7467816522935, 2043.4074118505466, 2079.9584008320053, 2091.8099897655893, 483.4029972983363, 503.87403149212696, 977.8924318324979, 1039.7385686132632, 1104.8784633690289, 12126.939365303117, 12578.433970471351, 12634.368281585885, 12819.230846149074, 12907.870921290667, 491.9065377578249, 492.8028788484635, 498.7979868153404, 500.30457663847284, 509.8037255656599, 6573.638449885207, 6834.897476537856, 6860.794176174722, 6875.449964002868, 6910.7581234656545, 528700.0, 533742.0, 536714.0, 541278.0, 547506.0, 94226.0, 95922.52038739901, 96484.51757741167, 96712.0, 98500.50749746205, 1075.9677209683723, 1094.4035500652165, 1117.402189828438, 1127.6447918905683, 1130.9547618095185, 17928.641427171122, 18184.36354727578, 18393.632127357647, 18700.076594813825, 18855.057247137313, 43041.78479107584, 43262.0, 44490.0, 44604.0, 45132.77433612811, 61586.0, 62483.37516624776, 63058.36941630708, 63177.36822631715, 65024.349756501826, 8821.691240806542, 8939.284857211538, 9032.774180645536, 9093.363464557417, 9166.67916622915, 10399.428031458343, 13350.866491334962, 13636.318184090558, 13697.931510342783, 13800.723985520433, 12032.578859739868, 12046.819297710717, 12425.31660758667, 12507.874921250672, 12594.937025315181, 11868.525258989544, 12226.75546489083, 12374.13381063317, 12444.937775311066, 12646.557370491988, 12129.393530323625, 12265.141440099107, 12284.754304914031, 12411.687788677043, 12587.93706031464, 8897.377183597346, 8967.103289671242, 9024.774380640536, 9140.131687489678, 9214.078592140731, 9452.243820494206, 11874.228175168586, 12459.065570082099, 12520.74958500843, 12579.622611321309], "value": 9329.195434543042, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.8065711128995, 1523.5505525869867, 1571.8113826340837, 1747.2923465996269, 1896.3362823011948, 9044.5929933153, 9478.0, 9514.286428517871, 9693.951530242339, 9717.659881904136, 9388.812223755522, 9455.196308313785, 9503.239740820734, 9518.0, 9536.713898583037, 2474.059857254241, 2500.6749122614033, 2501.0871032073346, 2501.874906254689, 2504.3864253257975, 2446.9233537243645, 2530.595104783235, 2533.3033415810705, 2533.429978254887, 2535.4168541235526, 2507.53610582042, 2531.455737016541, 2555.591105423133, 2556.360909772557, 2559.833610815291, 2428.4171798768257, 2447.9755202447927, 2451.4974430241846, 2460.409501719583, 2471.851688898672, 2366.1718398560365, 2398.3164798032653, 2402.9519409611885, 2405.9037638494437, 2439.926802195919, 8999.550022498848, 9354.485503297316, 9617.134457898823, 9617.759556011086, 9623.663171789023, 5351.491608297206, 5536.944630553723, 5609.439056094398, 5629.71851407432, 5632.746526406321, 3551.982240088809, 3559.4660800879815, 3646.781193128421, 3663.937458137137, 3666.926661466756, 1058.8252938265255, 1081.859358283418, 1088.9183311251688, 1111.5942680921487, 1114.3369695031442, 13169.407376668072, 13199.274039927801, 13375.665608359772, 13401.994850386163, 13403.396847141901, 4896.408667183108, 4977.975110124498, 5200.141976573898, 5201.921971170435, 5236.005159019774, 11688.70778230551, 11692.94153529227, 11705.239159454779, 11713.062954963583, 11730.354830484235, 5849.590528662953, 5861.267341582302, 5867.530597552186, 5872.0, 5876.206712093898, 4542.841000564964, 4544.136614043321, 4677.6257899367965, 5137.33214682089, 5289.444608316148, 4403.207422663887, 4424.823007079745, 4445.199864024442, 4477.19410506112, 4548.249538826057, 1405.395679857671, 1419.950301739434, 1422.139605538655, 1427.3291552970088, 1440.0351764317932, 1051.090806452413, 1059.3061544688242, 1063.680895731293, 1064.7657515346611, 1084.810158222308, 6256.499480041586, 6410.038494225903, 6523.804285871432, 6589.967050164719, 6681.432078273304, 487.1547864455167, 488.05561239002645, 497.2044728434548, 500.66705640748495, 505.9215821547666, 4463.2858742601675, 4646.4191976003, 4664.976675116671, 4685.4143232095985, 4699.483056863743, 11683.649490515298, 12142.0, 12143.878561214275, 12146.817797733042, 12151.5139394425, 12130.0, 12130.332831694164, 12135.878641213474, 12137.878621213675, 12142.939285303517, 8457.0, 8794.956025219833, 8811.911880881109, 9072.727818165466, 9080.772980675403, 6554.311797261219, 6660.167479065117, 6710.094137291403, 6866.141732283465, 6955.269696681978, 10607.363558186533, 11193.66419007431, 11197.496112674786, 11240.943795280971, 11345.149113816331, 9823.607055717692, 10159.23805714589, 11291.830622540832, 11354.432278386214, 11392.601258955896, 8244.958775206085, 8428.4942903426, 8493.660253589787, 8660.610272537624, 8680.78298042554, 9686.418814871131, 9707.320487565803, 9716.805663886824, 9721.270904682036, 9735.464549449849, 8251.546164960984, 8287.0, 8542.44474109181, 8655.610497527752, 8667.349948753743, 8319.126491718282, 8520.14798520143, 8530.914690853013, 8570.48577085377, 8644.82710345777, 7673.616319183907, 7722.227777222233, 7746.30283274508, 7748.883766743391, 7783.299503044753, 5615.887682246251, 5650.773969041193, 5658.490735833795, 5687.488126068673, 5893.145493903446, 5487.643303185439, 5521.558275337883, 5572.4148964358155, 5597.356304025089, 5610.270664813711], "value": 4948.2931381812705, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.7311097344071, 353.4309761284332, 358.74794983474806, 362.0947630922693, 364.95438070241204, 5553.91698618769, 5569.470900264479, 5569.637973531718, 5570.749316280762, 5584.134459158827, 5208.921866172011, 5290.709011004393, 5390.245365648802, 5424.376196737377, 5517.5585953123755, 4306.870793876189, 4317.4819021717285, 4318.114786468782, 4319.568043195671, 4339.479262488498, 4330.112326972963, 4352.867095123383, 4354.869353919387, 4365.454318210223, 4375.453068366454, 4150.315197992328, 4274.781687219143, 4290.635295999844, 4309.418228539138, 4321.092570560186, 4269.295566231568, 4272.6795490338345, 4273.102648443831, 4289.828406863721, 4291.935620965688, 3780.414035824424, 3828.942565861514, 3836.3669994450884, 3837.270918525499, 3843.1929294848114, 5175.560077393426, 5274.129768588178, 5313.256144139824, 5420.972895135538, 5437.075697131458, 4874.878128046792, 4880.951190488086, 4882.41411030675, 4895.510448955111, 4905.362302900636, 5664.49019588239, 5674.773009079632, 5677.801276955287, 5698.886022279573, 5711.086226203829, 3974.5627980922363, 4100.282450571168, 4159.750414975111, 4188.727732697365, 4193.245215861144, 5892.499137573312, 5902.61632993854, 5921.822345329647, 5927.673977931212, 5940.198073260141, 4233.534311225762, 4236.894077648022, 4267.765272909957, 4288.721233119837, 4296.334068219431, 4171.728837625545, 4267.5732426757695, 4293.724326107168, 4340.0, 4340.587644173798, 4849.2726091085915, 4894.1679914414635, 4895.7062576245535, 4905.0, 4909.214525675875, 5771.0, 5804.839032193494, 5817.0, 5823.126531020296, 5826.359100498942, 1412.6256542016374, 1432.5129455984807, 1451.7096580683906, 1458.8103546538882, 1480.29685899197, 928.4568527411404, 936.7681498829018, 949.9550494456181, 951.5194826612517, 953.9284553658504, 4926.852194434173, 4957.727324997088, 4975.875603109951, 4988.975055124701, 5008.874778130576, 5171.224316352502, 5182.404023537341, 5183.870403239949, 5238.345206849144, 5238.92141617876, 4883.3651625288485, 4935.531124543163, 4966.311454105621, 4987.650864439454, 5242.898991211812, 3104.410162069245, 3126.937461250762, 3130.906072817819, 3138.3095718941795, 3138.827364494929, 6121.265448146251, 6121.663308517986, 6134.006447904449, 6169.383061693795, 6185.907211391833, 5872.941270587325, 5922.690150841176, 5941.197938278277, 5982.4316689914385, 6002.579819412599, 6051.546134039964, 6071.0, 6093.360197179321, 6118.632882027182, 6120.540959427972, 5586.301712285965, 5906.995810712275, 5908.970455147697, 5916.911246331395, 5919.792807251725, 5863.648181109147, 5865.912011319919, 5870.9432302186015, 5880.26496687914, 5883.176355310175, 6135.085834228706, 6147.754089836357, 6171.580536476546, 6181.53638477125, 6182.165407669907, 4056.411820286101, 4089.2026054919006, 4194.0, 4247.171801498678, 4295.119500502327, 5854.209681693087, 5914.615549989237, 5945.346011938596, 5951.851203719942, 6020.458158765732, 5749.453801888814, 5793.275840519935, 5793.594448388572, 5814.476697097281, 5817.447342502455, 5604.523615492729, 5709.885802284014, 5744.253247078021, 5761.91357129635, 5786.247787787561, 5739.5695322850115, 5751.1373294006235, 5760.510356619734, 5779.786244888621, 5789.899919015374, 5590.0, 5761.942380576141, 5808.82573522795, 5842.941570584239, 5844.444777746107, 5602.5517958562405, 5627.212190293444, 5627.971860140673, 5649.237352957298, 5651.858703532444], "value": 4360.666043656431, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [805.7784109369923, 828.9129641387655, 834.8872902158208, 875.3391189651812, 896.2471523919907, 16812.0, 18106.81893181071, 18113.366032188875, 18165.182566784493, 18166.72749908751, 14524.709505809877, 14539.78190327146, 14562.927185364058, 14586.489472868452, 14612.050216735905, 731.162818572734, 873.4235404632911, 881.0264657553407, 882.2104216726059, 901.324006994754, 878.4553576782404, 882.6116508736168, 886.3352485635774, 887.3877024852864, 887.6626881784913, 961.9759506012371, 964.9035096490366, 966.1739212972877, 966.1787480641469, 966.6230170233614, 763.7441457111846, 912.0605776050678, 940.050548945563, 946.1342871272777, 951.172479942451, 700.9754658586976, 704.2745971649209, 704.4294121761338, 704.9471289653245, 705.3193668110298, 15451.381944722198, 15508.612284692974, 15520.92239538795, 15522.611934701723, 15525.0, 15475.767863482057, 15590.532284031497, 15683.215839208107, 15765.0, 16033.198340082949, 14072.788908166283, 14099.859001409957, 14211.624975712826, 14225.78661320081, 14390.928045359811, 4686.929696054528, 4765.0, 4776.832810851603, 4781.784819683123, 4790.0, 67242.0, 68473.97289040669, 69403.0, 82783.5860820698, 82942.0, 12366.319852408024, 12565.93717031409, 12858.228506289652, 12912.548060817826, 13035.0, 22974.65538016931, 22984.195553155558, 22985.655215171784, 22990.540189196123, 22995.195168169033, 9368.906310936803, 9396.43245012418, 9544.475053872104, 9643.855342169873, 9813.264005199639, 5034.84895453137, 5043.041822053798, 5067.974660126676, 5175.404828444702, 5395.487428694268, 3541.203229273393, 3669.798161101165, 3681.2269423420844, 3682.558093028854, 3687.2994131115, 601.8044135655929, 668.7793028300649, 669.5647828911247, 677.1366507702719, 695.8956156576554, 714.3677845107118, 754.97357592484, 784.7371130671175, 803.2088392932957, 839.5298632765549, 10410.167186625053, 10595.0, 11200.943995279971, 11252.43737813106, 11273.379964101889, 813.1299509524752, 823.5799742131557, 827.230675471817, 828.0808302783905, 829.4484168028314, 4836.322914791932, 5123.692578445305, 5133.717645529532, 5160.509751573594, 5167.483251674803, 108908.0, 126760.0, 129315.0, 132156.33921830522, 135925.0, 63448.68275658592, 100472.49763751135, 112783.0, 114346.0, 116476.41761791306, 92946.0, 93360.0, 93707.0, 94122.0, 95755.56366654506, 9609.855852162083, 9684.75788105303, 9906.702798916043, 9932.602695892083, 9935.354201976848, 54912.725436372566, 56334.0, 56796.0, 57300.42699572951, 58395.0, 78525.0, 86044.56977715321, 86576.13423865681, 87172.1282787164, 91089.54455227946, 12447.626571202878, 12450.688732781753, 12765.87234127684, 12862.935685321514, 13030.4136313868, 15289.847101529287, 15499.922500387876, 16156.434524791575, 16435.26041328119, 17219.741703874202, 14146.646333841736, 15150.696986059997, 15395.230238487808, 16044.759328609847, 16608.916955415145, 14024.579262622137, 14508.34712437964, 14553.19957402353, 15175.848241517444, 15647.68704625924, 14667.779983300472, 15013.324400401789, 15197.620059498158, 16192.271347789614, 16224.18879056066, 11678.766424671288, 12809.10336276489, 12876.484940602646, 13000.739985200433, 13645.249511276976, 13012.958963283105, 13324.866751332362, 13364.732705346034, 13562.321883905568, 13573.921659422303], "value": 8280.938228787756, "unit": "score"}], "name": "dromaeo_css", "value": 7635.968272248526, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 111.0, 110.0, 112.0, 109.0, 109.0, 111.0, 109.0, 110.0], "value": 109.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 118.0, 115.0, 167.0, 119.0, 116.0, 151.0, 119.0, 145.0, 120.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 161.0, 159.0, 159.0, 160.0, 160.0, 160.0, 159.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 73.0, 123.0, 75.0, 76.0, 76.0, 76.0, 115.0, 76.0], "value": 83.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 128.0, 83.0, 84.0, 83.0, 127.0, 83.0, 83.0, 124.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 116.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 50.0, 51.0, 50.0, 51.0, 52.0, 51.0, 50.0, 49.0], "value": 50.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 60.0, 61.0, 60.0, 61.0, 59.0, 60.0, 61.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 73.0, 82.0, 82.0, 72.0, 75.0, 72.0, 73.0, 72.0, 73.0], "value": 75.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 122.0, 129.0, 124.0, 120.0, 124.0, 118.0, 129.0, 148.0, 119.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 153.0, 155.0, 156.0, 150.0, 152.0, 155.0, 149.0, 151.0, 150.0], "value": 152.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 62.0, 61.0, 61.0, 64.0, 61.0, 60.0, 61.0, 58.0], "value": 61.1, "unit": "score"}], "name": "kraken", "value": 1462.6999999999998, "alertThreshold": 2.0}]} 07:20:18 INFO - Return code: 0 07:20:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:20:18 INFO - # TBPL SUCCESS # 07:20:18 INFO - Running post-action listener: _resource_record_post_action 07:20:18 INFO - [mozharness: 2016-09-01 14:20:18.900021Z] Finished run-tests step (success) 07:20:18 INFO - Running post-run listener: _resource_record_post_run 07:20:18 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 328572928; Read time: 12; Write time: 304840 07:20:18 INFO - TinderboxPrint: CPU usage
12.9% 07:20:18 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 07:20:18 INFO - TinderboxPrint: I/O write bytes / time
328,572,928 / 304,840 07:20:18 INFO - TinderboxPrint: CPU idle
8,275.1 (87.1%) 07:20:18 INFO - TinderboxPrint: CPU user
1,184.2 (12.5%) 07:20:18 INFO - TinderboxPrint: Swap in / out
0 / 0 07:20:18 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79233024; Read time: 0; Write time: 115860 07:20:18 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 248926208; Read time: 0; Write time: 188840 07:20:19 INFO - Running post-run listener: _upload_blobber_files 07:20:19 INFO - Blob upload gear active. 07:20:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:20:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:20:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:20:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:20:19 INFO - (blobuploader) - INFO - Open directory for files ... 07:20:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:20:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:20:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:21 INFO - (blobuploader) - INFO - Done attempting. 07:20:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:20:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:20:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:22 INFO - (blobuploader) - INFO - Done attempting. 07:20:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:20:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:20:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:29 INFO - (blobuploader) - INFO - Done attempting. 07:20:29 INFO - (blobuploader) - INFO - Iteration through files over. 07:20:29 INFO - Return code: 0 07:20:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:20:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:20:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecd82cdd27a34d9a7069002006c5ac82fd1946959f6be8f8ad92b5bd4a7a3e386418e0786cb07ec65414563f79fe67e4c7f7b59d7896018c9a1628a5a3c58ba1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4893ca39252eec478cdf9e1d29a508aa08b6d49aa48917f7b5039df27f27a01aa94502b7f3bf1136831c20d0de7b3290146e2a672d3f0b2df565105439b2426b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a985eb8cb43f35a1cfe991ba3875a7dede4a2b532bbadb3b76debe34a6f2b802b5f55739583b5686d32c6b8ac26176efa8e2967a9d758e44ad98e14b3dab4c46"} 07:20:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:20:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:20:29 INFO - Contents: 07:20:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecd82cdd27a34d9a7069002006c5ac82fd1946959f6be8f8ad92b5bd4a7a3e386418e0786cb07ec65414563f79fe67e4c7f7b59d7896018c9a1628a5a3c58ba1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4893ca39252eec478cdf9e1d29a508aa08b6d49aa48917f7b5039df27f27a01aa94502b7f3bf1136831c20d0de7b3290146e2a672d3f0b2df565105439b2426b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a985eb8cb43f35a1cfe991ba3875a7dede4a2b532bbadb3b76debe34a6f2b802b5f55739583b5686d32c6b8ac26176efa8e2967a9d758e44ad98e14b3dab4c46"} 07:20:29 INFO - Running post-run listener: copy_logs_to_upload_dir 07:20:29 INFO - Copying logs to upload dir... 07:20:29 INFO - mkdir: /builds/slave/test/build/upload/logs 07:20:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1683.164404 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 3 secs) (at 2016-09-01 07:20:28.903911) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 07:20:28.907517) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689 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/ecd82cdd27a34d9a7069002006c5ac82fd1946959f6be8f8ad92b5bd4a7a3e386418e0786cb07ec65414563f79fe67e4c7f7b59d7896018c9a1628a5a3c58ba1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4893ca39252eec478cdf9e1d29a508aa08b6d49aa48917f7b5039df27f27a01aa94502b7f3bf1136831c20d0de7b3290146e2a672d3f0b2df565105439b2426b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a985eb8cb43f35a1cfe991ba3875a7dede4a2b532bbadb3b76debe34a6f2b802b5f55739583b5686d32c6b8ac26176efa8e2967a9d758e44ad98e14b3dab4c46"} build_url:https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011339 build_url: 'https://queue.taskcluster.net/v1/task/Y0YKx_xFT_uGQ-Y5HIenrg/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/ecd82cdd27a34d9a7069002006c5ac82fd1946959f6be8f8ad92b5bd4a7a3e386418e0786cb07ec65414563f79fe67e4c7f7b59d7896018c9a1628a5a3c58ba1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4893ca39252eec478cdf9e1d29a508aa08b6d49aa48917f7b5039df27f27a01aa94502b7f3bf1136831c20d0de7b3290146e2a672d3f0b2df565105439b2426b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a985eb8cb43f35a1cfe991ba3875a7dede4a2b532bbadb3b76debe34a6f2b802b5f55739583b5686d32c6b8ac26176efa8e2967a9d758e44ad98e14b3dab4c46"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 07:20:28.932640) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 07:20:28.932964) ========= 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=14ef55db36f7623abb31b9cf000001d5-1472734858.859721-1496274689 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004516 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 07:20:29.015948) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:20:29.016275) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:20:29.016572) ========= ========= Total master_lag: 0.20 =========