builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-064 starttime: 1472737945.5 results: success (0) buildid: 20160830104522 builduid: 5655a65e53c8465d859cdd3cc9117707 revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.504207) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.504749) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:25.505219) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.065238) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.065490) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 06:52:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 49.6M=0s 2016-09-01 06:52:28 (49.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128948 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.243452) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.243784) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018639 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.355399) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:26.355728) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 06:52:28,415 truncating revision to first 12 chars 2016-09-01 06:52:28,415 Setting DEBUG logging. 2016-09-01 06:52:28,415 attempt 1/10 2016-09-01 06:52:28,415 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:28,753 unpacking tar archive at: mozilla-aurora-bac8c738419e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.648868 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.070610) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.071151) ========= script_repo_revision: bac8c738419eb9ff1fe0947120e879707d19c97b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.071547) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.071826) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 06:52:27.099175) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 5 secs) (at 2016-09-01 06:52:27.099422) ========= /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=a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:52:29 INFO - MultiFileLogger online at 20160901 06:52:29 in /builds/slave/test 06:52:29 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:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:52:29 INFO - {'append_to_log': False, 06:52:29 INFO - 'base_work_dir': '/builds/slave/test', 06:52:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 06:52:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:52:29 INFO - 'branch': 'Mozilla-Aurora', 06:52:29 INFO - 'buildbot_json_path': 'buildprops.json', 06:52:29 INFO - 'config_files': ('talos/linux_config.py',), 06:52:29 INFO - 'default_actions': ('clobber', 06:52:29 INFO - 'read-buildbot-config', 06:52:29 INFO - 'download-and-extract', 06:52:29 INFO - 'populate-webroot', 06:52:29 INFO - 'create-virtualenv', 06:52:29 INFO - 'install', 06:52:29 INFO - 'run-tests'), 06:52:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:52:29 INFO - 'download_minidump_stackwalk': True, 06:52:29 INFO - 'download_symbols': 'ondemand', 06:52:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:52:29 INFO - 'tooltool.py': '/tools/tooltool.py', 06:52:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:52:29 INFO - '/tools/misc-python/virtualenv.py')}, 06:52:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:52:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:52:29 INFO - 'installer_path': 'installer.exe', 06:52:29 INFO - 'log_level': 'info', 06:52:29 INFO - 'log_name': 'talos', 06:52:29 INFO - 'log_to_console': True, 06:52:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:52:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:52:29 INFO - 'opt_config_files': (), 06:52:29 INFO - 'pip_index': False, 06:52:29 INFO - 'sps_profile': False, 06:52:29 INFO - 'sps_profile_interval': 0, 06:52:29 INFO - 'suite': 'dromaeojs', 06:52:29 INFO - 'system_bits': '32', 06:52:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:52:29 INFO - 'title': 'talos-linux64-ix-064', 06:52:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:52:29 INFO - 'use_talos_json': True, 06:52:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:52:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:52:29 INFO - 'work_dir': 'build'} 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.200964Z] Running clobber step. 06:52:29 INFO - Running pre-action listener: _resource_record_pre_action 06:52:29 INFO - Running main action method: clobber 06:52:29 INFO - rmtree: /builds/slave/test/build 06:52:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:52:29 INFO - Running post-action listener: _resource_record_post_action 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.665744Z] Finished clobber step (success) 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.665847Z] Running read-buildbot-config step. 06:52:29 INFO - Running pre-action listener: _resource_record_pre_action 06:52:29 INFO - Running main action method: read_buildbot_config 06:52:29 INFO - Using buildbot properties: 06:52:29 INFO - { 06:52:29 INFO - "project": "", 06:52:29 INFO - "product": "firefox", 06:52:29 INFO - "script_repo_revision": "production", 06:52:29 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 06:52:29 INFO - "repository": "", 06:52:29 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 06:52:29 INFO - "buildid": "20160830104522", 06:52:29 INFO - "pgo_build": "True", 06:52:29 INFO - "basedir": "/builds/slave/test", 06:52:29 INFO - "buildnumber": 718, 06:52:29 INFO - "slavename": "talos-linux64-ix-064", 06:52:29 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 06:52:29 INFO - "platform": "ubuntu64_hw", 06:52:29 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 06:52:29 INFO - "branch": "mozilla-aurora", 06:52:29 INFO - "repo_path": "releases/mozilla-aurora", 06:52:29 INFO - "revision": "bac8c738419eb9ff1fe0947120e879707d19c97b", 06:52:29 INFO - "stage_platform": "linux64-pgo", 06:52:29 INFO - "builduid": "5655a65e53c8465d859cdd3cc9117707", 06:52:29 INFO - "slavebuilddir": "test" 06:52:29 INFO - } 06:52:29 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:29 INFO - Running post-action listener: _resource_record_post_action 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.668270Z] Finished read-buildbot-config step (success) 06:52:29 INFO - [mozharness: 2016-09-01 13:52:29.668359Z] Running download-and-extract step. 06:52:29 INFO - Running pre-action listener: _resource_record_pre_action 06:52:29 INFO - Running main action method: download_and_extract 06:52:29 INFO - mkdir: /builds/slave/test/build/tests 06:52: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:52:29 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: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.test_packages.json 06:52: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.test_packages.json 06:52: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.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:52:29 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: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.test_packages.json 06:52:29 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:52:29 INFO - retry: Failed, sleeping 30 seconds before retrying 06:52:59 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: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.test_packages.json 06:52:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:52:59 INFO - retry: Failed, sleeping 60 seconds before retrying 06:53:59 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: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.test_packages.json 06:53:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:53: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.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 INFO - Caught exception: HTTP Error 404: Not Found 06:53:59 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:59 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:59 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:54:00 INFO - Downloaded 1361 bytes. 06:54:00 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 06:54:00 INFO - Using the following test package requirements: 06:54:00 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:54:00 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:54:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 06:54:00 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:54:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 06:54:00 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:54:00 INFO - u'jsshell-linux-x86_64.zip'], 06:54:00 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:54:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 06:54:00 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 06:54:00 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:54:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 06:54:00 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:54:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 06:54:00 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:54:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 06:54:00 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 06:54:00 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 06:54:00 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:54:00 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:54:00 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:54:00 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:54:00 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:54:00 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:54:00 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:54:00 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:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:54:00 INFO - retry: Failed, sleeping 30 seconds before retrying 06:54:30 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:30 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:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:54:30 INFO - retry: Failed, sleeping 60 seconds before retrying 06:55:30 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:30 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:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:55:30 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:30 INFO - Caught exception: HTTP Error 404: Not Found 06:55:30 INFO - Caught exception: HTTP Error 404: Not Found 06:55:30 INFO - Caught exception: HTTP Error 404: Not Found 06:55:30 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:30 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:30 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:31 INFO - Downloaded 21769513 bytes. 06:55:31 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:31 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:32 INFO - Return code: 0 06:55:32 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: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:55:32 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: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.common.tests.zip 06:55: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.common.tests.zip 06:55: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.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 06:55:32 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: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.common.tests.zip 06:55:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:55:32 INFO - retry: Failed, sleeping 30 seconds before retrying 06:56:02 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:56: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.common.tests.zip 06:56:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:56:02 INFO - retry: Failed, sleeping 60 seconds before retrying 06:57:02 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:57: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.common.tests.zip 06:57:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:57:02 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:57:02 INFO - Caught exception: HTTP Error 404: Not Found 06:57:02 INFO - Caught exception: HTTP Error 404: Not Found 06:57:02 INFO - Caught exception: HTTP Error 404: Not Found 06:57:02 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:57:02 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:57:02 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:03 INFO - Downloaded 21769513 bytes. 06:57:03 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:03 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:04 INFO - Return code: 0 06:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:57:04 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:04 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:04 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:04 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:04 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:04 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:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:57:04 INFO - retry: Failed, sleeping 30 seconds before retrying 06:57:35 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:35 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:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:57:35 INFO - retry: Failed, sleeping 60 seconds before retrying 06:58:35 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:35 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:35 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 06:58:35 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:35 INFO - Caught exception: HTTP Error 404: Not Found 06:58:35 INFO - Caught exception: HTTP Error 404: Not Found 06:58:35 INFO - Caught exception: HTTP Error 404: Not Found 06:58:35 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:35 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:35 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:35 INFO - Downloaded 14413723 bytes. 06:58:35 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:35 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:36 INFO - Return code: 0 06:58:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:58:36 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:36 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:36 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:36 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:36 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:36 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:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 06:58:36 INFO - retry: Failed, sleeping 30 seconds before retrying 06:59:06 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:06 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:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 06:59:06 INFO - retry: Failed, sleeping 60 seconds before retrying 07:00:06 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:06 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:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:00:06 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:06 INFO - Caught exception: HTTP Error 404: Not Found 07:00:06 INFO - Caught exception: HTTP Error 404: Not Found 07:00:06 INFO - Caught exception: HTTP Error 404: Not Found 07:00:06 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:06 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:06 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:07 INFO - Downloaded 63889067 bytes. 07:00:07 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:07 INFO - mkdir: /builds/slave/test/properties 07:00:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:00:07 INFO - Writing to file /builds/slave/test/properties/build_url 07:00:07 INFO - Contents: 07:00:07 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:08 INFO - Running post-action listener: _resource_record_post_action 07:00:08 INFO - Running post-action listener: set_extra_try_arguments 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.028835Z] Finished download-and-extract step (success) 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.029071Z] Running populate-webroot step. 07:00:08 INFO - Running pre-action listener: _resource_record_pre_action 07:00:08 INFO - Running main action method: populate_webroot 07:00:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:00:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:00:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:00:08 INFO - '--tppagecycles', 07:00:08 INFO - '7'], 07:00:08 INFO - 'tests': ['tsvgm']}}, 07:00:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:00:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:00:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:00:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:00:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['damp', 'tps']}, 07:00:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'tests': ['damp', 'tps']}, 07:00:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['dromaeo_dom']}, 07:00:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:00:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['basic_compositor_video']}, 07:00:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:00:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other-e10s': {'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['a11yr', 07:00:08 INFO - 'ts_paint', 07:00:08 INFO - 'tpaint', 07:00:08 INFO - 'sessionrestore', 07:00:08 INFO - 'sessionrestore_no_auto_restore', 07:00:08 INFO - 'tabpaint']}, 07:00:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tsvgx', 07:00:08 INFO - 'tsvgr_opacity', 07:00:08 INFO - 'tart', 07:00:08 INFO - 'tscrollx', 07:00:08 INFO - 'cart']}, 07:00:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:00:08 INFO - 'tsvgr_opacity', 07:00:08 INFO - 'tart', 07:00:08 INFO - 'tscrollx', 07:00:08 INFO - 'cart']}, 07:00:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s'], 07:00:08 INFO - 'tests': ['tp5o']}, 07:00:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'tests': ['tp5o']}, 07:00:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--disable-e10s', 07:00:08 INFO - '--xperf_path', 07:00:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:08 INFO - 'tests': ['tp5n']}, 07:00:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:08 INFO - 'talos_options': ['--xperf_path', 07:00:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:08 INFO - 'tests': ['tp5n']}}, 07:00:08 INFO - 'talos.zip': {'path': '', 07:00:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:00:08 INFO - Running post-action listener: _resource_record_post_action 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.049728Z] Finished populate-webroot step (success) 07:00:08 INFO - [mozharness: 2016-09-01 14:00:08.049816Z] Running create-virtualenv step. 07:00:08 INFO - Running pre-action listener: _resource_record_pre_action 07:00:08 INFO - Running main action method: create_virtualenv 07:00:08 INFO - Creating virtualenv /builds/slave/test/build/venv 07:00:08 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:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:00:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:00:08 INFO - Using real prefix '/usr' 07:00:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:00:08 INFO - Installing distribute.............................................................................................................................................................................................done. 07:00:10 INFO - Installing pip.................done. 07:00:10 INFO - Return code: 0 07:00:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 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 0x1700030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bf3b0>, '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 0x19c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c0740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', '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:10 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:10 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:10 INFO - Using env: {'DISPLAY': ':0', 07:00:10 INFO - 'HOME': '/home/cltbld', 07:00:10 INFO - 'LANG': 'en_US.UTF-8', 07:00:10 INFO - 'LANGUAGE': 'en_US:en', 07:00:10 INFO - 'LOGNAME': 'cltbld', 07:00:10 INFO - 'MAIL': '/var/mail/cltbld', 07:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:10 INFO - 'MOZ_NO_REMOTE': '1', 07:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:10 INFO - 'NO_EM_RESTART': '1', 07:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:10 INFO - 'PWD': '/builds/slave/test', 07:00:10 INFO - 'SHELL': '/bin/bash', 07:00:10 INFO - 'SHLVL': '1', 07:00:10 INFO - 'TERM': 'linux', 07:00:10 INFO - 'TMOUT': '86400', 07:00:10 INFO - 'USER': 'cltbld', 07:00:10 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', 07:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:10 INFO - '_': '/tools/buildbot/bin/python'} 07:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:10 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:10 INFO - Cleaning up... 07:00:10 INFO - Return code: 0 07:00:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:10 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 0x1700030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bf3b0>, '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 0x19c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c0740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', '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:10 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:10 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:10 INFO - Using env: {'DISPLAY': ':0', 07:00:10 INFO - 'HOME': '/home/cltbld', 07:00:10 INFO - 'LANG': 'en_US.UTF-8', 07:00:10 INFO - 'LANGUAGE': 'en_US:en', 07:00:10 INFO - 'LOGNAME': 'cltbld', 07:00:10 INFO - 'MAIL': '/var/mail/cltbld', 07:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:10 INFO - 'MOZ_NO_REMOTE': '1', 07:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:10 INFO - 'NO_EM_RESTART': '1', 07:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:10 INFO - 'PWD': '/builds/slave/test', 07:00:10 INFO - 'SHELL': '/bin/bash', 07:00:10 INFO - 'SHLVL': '1', 07:00:10 INFO - 'TERM': 'linux', 07:00:10 INFO - 'TMOUT': '86400', 07:00:10 INFO - 'USER': 'cltbld', 07:00:10 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', 07:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:10 INFO - '_': '/tools/buildbot/bin/python'} 07:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:11 INFO - Downloading/unpacking psutil>=3.1.1 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 - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:00:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:13 INFO - Installing collected packages: psutil 07:00:13 INFO - Running setup.py install for psutil 07:00:13 INFO - building 'psutil._psutil_linux' extension 07:00:13 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:13 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:13 INFO - building 'psutil._psutil_posix' extension 07:00:13 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:13 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:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:13 INFO - Successfully installed psutil 07:00:13 INFO - Cleaning up... 07:00:13 INFO - Return code: 0 07:00:13 INFO - Installing mozsystemmonitor==0.3 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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1700030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bf3b0>, '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 0x19c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c0740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', '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', 'mozsystemmonitor==0.3'] 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 mozsystemmonitor==0.3 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': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', 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 mozsystemmonitor==0.3 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 mozsystemmonitor-0.3.tar.gz 07:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:00:15 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:15 INFO - Installing collected packages: mozsystemmonitor 07:00:15 INFO - Running setup.py install for mozsystemmonitor 07:00:15 INFO - Successfully installed mozsystemmonitor 07:00:15 INFO - Cleaning up... 07:00:15 INFO - Return code: 0 07:00:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1700030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bf3b0>, '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 0x19c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c0740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', '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:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:00:15 INFO - Using env: {'DISPLAY': ':0', 07:00:15 INFO - 'HOME': '/home/cltbld', 07:00:15 INFO - 'LANG': 'en_US.UTF-8', 07:00:15 INFO - 'LANGUAGE': 'en_US:en', 07:00:15 INFO - 'LOGNAME': 'cltbld', 07:00:15 INFO - 'MAIL': '/var/mail/cltbld', 07:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:15 INFO - 'MOZ_NO_REMOTE': '1', 07:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:15 INFO - 'NO_EM_RESTART': '1', 07:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:15 INFO - 'PWD': '/builds/slave/test', 07:00:15 INFO - 'SHELL': '/bin/bash', 07:00:15 INFO - 'SHLVL': '1', 07:00:15 INFO - 'TERM': 'linux', 07:00:15 INFO - 'TMOUT': '86400', 07:00:15 INFO - 'USER': 'cltbld', 07:00:15 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', 07:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:15 INFO - '_': '/tools/buildbot/bin/python'} 07:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:16 INFO - Downloading/unpacking jsonschema==2.5.1 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 jsonschema-2.5.1-py2.py3-none-any.whl 07:00:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 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 - Downloading functools32-3.2.3-2.tar.gz 07:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:00:18 INFO - warning: no files found matching '*.txt' 07:00:18 INFO - no previously-included directories found matching 'build' 07:00:18 INFO - no previously-included directories found matching 'dist' 07:00:18 INFO - no previously-included directories found matching '.git*' 07:00:18 INFO - Installing collected packages: jsonschema, functools32 07:00:18 INFO - Running setup.py install for functools32 07:00:18 INFO - warning: no files found matching '*.txt' 07:00:18 INFO - no previously-included directories found matching 'build' 07:00:18 INFO - no previously-included directories found matching 'dist' 07:00:18 INFO - no previously-included directories found matching '.git*' 07:00:18 INFO - Successfully installed jsonschema functools32 07:00:18 INFO - Cleaning up... 07:00:18 INFO - Return code: 0 07:00:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1700030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bf3b0>, '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 0x19c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c0740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', '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:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:00:18 INFO - Using env: {'DISPLAY': ':0', 07:00:18 INFO - 'HOME': '/home/cltbld', 07:00:18 INFO - 'LANG': 'en_US.UTF-8', 07:00:18 INFO - 'LANGUAGE': 'en_US:en', 07:00:18 INFO - 'LOGNAME': 'cltbld', 07:00:18 INFO - 'MAIL': '/var/mail/cltbld', 07:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:18 INFO - 'MOZ_NO_REMOTE': '1', 07:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:18 INFO - 'NO_EM_RESTART': '1', 07:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:18 INFO - 'PWD': '/builds/slave/test', 07:00:18 INFO - 'SHELL': '/bin/bash', 07:00:18 INFO - 'SHLVL': '1', 07:00:18 INFO - 'TERM': 'linux', 07:00:18 INFO - 'TMOUT': '86400', 07:00:18 INFO - 'USER': 'cltbld', 07:00:18 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', 07:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:18 INFO - '_': '/tools/buildbot/bin/python'} 07:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:00:18 INFO - Cleaning up... 07:00:18 INFO - Return code: 0 07:00:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1700030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bf3b0>, '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 0x19c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c0740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', '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:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:00:18 INFO - Using env: {'DISPLAY': ':0', 07:00:18 INFO - 'HOME': '/home/cltbld', 07:00:18 INFO - 'LANG': 'en_US.UTF-8', 07:00:18 INFO - 'LANGUAGE': 'en_US:en', 07:00:18 INFO - 'LOGNAME': 'cltbld', 07:00:18 INFO - 'MAIL': '/var/mail/cltbld', 07:00:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:18 INFO - 'MOZ_NO_REMOTE': '1', 07:00:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:18 INFO - 'NO_EM_RESTART': '1', 07:00:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:18 INFO - 'PWD': '/builds/slave/test', 07:00:18 INFO - 'SHELL': '/bin/bash', 07:00:18 INFO - 'SHLVL': '1', 07:00:18 INFO - 'TERM': 'linux', 07:00:18 INFO - 'TMOUT': '86400', 07:00:18 INFO - 'USER': 'cltbld', 07:00:18 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', 07:00:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:18 INFO - '_': '/tools/buildbot/bin/python'} 07:00:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:18 INFO - Downloading/unpacking 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:20 INFO - Downloading blobuploader-1.2.4.tar.gz 07:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:00:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:00:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:21 INFO - Downloading docopt-0.6.1.tar.gz 07:00:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:00:21 INFO - Installing collected packages: blobuploader, requests, docopt 07:00:21 INFO - Running setup.py install for blobuploader 07:00:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:00:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:00:21 INFO - Running setup.py install for requests 07:00:22 INFO - Running setup.py install for docopt 07:00:22 INFO - Successfully installed blobuploader requests docopt 07:00:22 INFO - Cleaning up... 07:00:22 INFO - Return code: 0 07:00:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:22 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:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:22 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:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:22 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 0x1700030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bf3b0>, '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 0x19c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c0740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', '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:22 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:22 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:22 INFO - Using env: {'DISPLAY': ':0', 07:00:22 INFO - 'HOME': '/home/cltbld', 07:00:22 INFO - 'LANG': 'en_US.UTF-8', 07:00:22 INFO - 'LANGUAGE': 'en_US:en', 07:00:22 INFO - 'LOGNAME': 'cltbld', 07:00:22 INFO - 'MAIL': '/var/mail/cltbld', 07:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:22 INFO - 'MOZ_NO_REMOTE': '1', 07:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:22 INFO - 'NO_EM_RESTART': '1', 07:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:22 INFO - 'PWD': '/builds/slave/test', 07:00:22 INFO - 'SHELL': '/bin/bash', 07:00:22 INFO - 'SHLVL': '1', 07:00:22 INFO - 'TERM': 'linux', 07:00:22 INFO - 'TMOUT': '86400', 07:00:22 INFO - 'USER': 'cltbld', 07:00:22 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', 07:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:22 INFO - '_': '/tools/buildbot/bin/python'} 07:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:22 INFO - Running setup.py (path:/tmp/pip-Glq0a9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:22 INFO - Running setup.py (path:/tmp/pip-I5yed0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:22 INFO - Running setup.py (path:/tmp/pip-kUTVVl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:22 INFO - Running setup.py (path:/tmp/pip-hCsZSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:22 INFO - Running setup.py (path:/tmp/pip-5buY4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:22 INFO - Running setup.py (path:/tmp/pip-djOIA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:22 INFO - Running setup.py (path:/tmp/pip-xw4hlr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:22 INFO - Running setup.py (path:/tmp/pip-IBwxY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:23 INFO - Running setup.py (path:/tmp/pip-HWLdhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:23 INFO - Running setup.py (path:/tmp/pip-mMhRSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:23 INFO - Running setup.py (path:/tmp/pip-i2pwru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:23 INFO - Running setup.py (path:/tmp/pip-HHJczV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:23 INFO - Running setup.py (path:/tmp/pip-1355b5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:23 INFO - Running setup.py (path:/tmp/pip-reIwGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:23 INFO - Running setup.py (path:/tmp/pip-1CE9g_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:23 INFO - Running setup.py (path:/tmp/pip-G_QrKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:23 INFO - Running setup.py (path:/tmp/pip-rmzwYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:00:23 INFO - Running setup.py install for manifestparser 07:00:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:00:23 INFO - Running setup.py install for mozcrash 07:00:23 INFO - Running setup.py install for mozdebug 07:00:23 INFO - Running setup.py install for mozdevice 07:00:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozfile 07:00:24 INFO - Running setup.py install for mozhttpd 07:00:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozinfo 07:00:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozInstall 07:00:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozleak 07:00:24 INFO - Running setup.py install for mozlog 07:00:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for moznetwork 07:00:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Running setup.py install for mozprocess 07:00:24 INFO - Running setup.py install for mozprofile 07:00:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:00:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Running setup.py install for mozrunner 07:00:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Running setup.py install for mozscreenshot 07:00:25 INFO - Running setup.py install for moztest 07:00:25 INFO - Running setup.py install for mozversion 07:00:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:00:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:00:25 INFO - Cleaning up... 07:00:25 INFO - Return code: 0 07:00:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:25 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 0x1700030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bf3b0>, '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 0x19c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c0740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', '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:25 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:25 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:25 INFO - Using env: {'DISPLAY': ':0', 07:00:25 INFO - 'HOME': '/home/cltbld', 07:00:25 INFO - 'LANG': 'en_US.UTF-8', 07:00:25 INFO - 'LANGUAGE': 'en_US:en', 07:00:25 INFO - 'LOGNAME': 'cltbld', 07:00:25 INFO - 'MAIL': '/var/mail/cltbld', 07:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:25 INFO - 'MOZ_NO_REMOTE': '1', 07:00:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:25 INFO - 'NO_EM_RESTART': '1', 07:00:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:25 INFO - 'PWD': '/builds/slave/test', 07:00:25 INFO - 'SHELL': '/bin/bash', 07:00:25 INFO - 'SHLVL': '1', 07:00:25 INFO - 'TERM': 'linux', 07:00:25 INFO - 'TMOUT': '86400', 07:00:25 INFO - 'USER': 'cltbld', 07:00:25 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', 07:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:25 INFO - '_': '/tools/buildbot/bin/python'} 07:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:00:25 INFO - Running setup.py (path:/tmp/pip-JxJbed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:00:25 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:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:00:25 INFO - Running setup.py (path:/tmp/pip-k0hmOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:00:25 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:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:00:25 INFO - Running setup.py (path:/tmp/pip-95KoiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:00:25 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:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:00:25 INFO - Running setup.py (path:/tmp/pip-UP1uh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:00:25 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:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:00:25 INFO - Running setup.py (path:/tmp/pip-hgyDWo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:00:26 INFO - Running setup.py (path:/tmp/pip-JgiCEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:00:26 INFO - Running setup.py (path:/tmp/pip-jcT4lb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:00:26 INFO - Running setup.py (path:/tmp/pip-M86V4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:00:26 INFO - Running setup.py (path:/tmp/pip-Wnh3_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:00:26 INFO - Running setup.py (path:/tmp/pip-YI4BZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:00:26 INFO - Running setup.py (path:/tmp/pip-vIfWqx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:00:26 INFO - Running setup.py (path:/tmp/pip-l9f72W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:00:26 INFO - Running setup.py (path:/tmp/pip-yLv3Mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:00:26 INFO - Running setup.py (path:/tmp/pip-G60WzL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:26 INFO - Running setup.py (path:/tmp/pip-uIzB6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:00:26 INFO - Running setup.py (path:/tmp/pip-ql6AXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:00:26 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:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:00:26 INFO - Running setup.py (path:/tmp/pip-y8KE5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:00:26 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:26 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:26 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:26 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:26 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:26 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:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:00:26 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:26 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 - 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:26 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:26 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:26 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:28 INFO - Downloading blessings-1.6.tar.gz 07:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:00:28 INFO - Installing collected packages: blessings 07:00:28 INFO - Running setup.py install for blessings 07:00:28 INFO - Successfully installed blessings 07:00:28 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:29 INFO - Reading from file tmpfile_stdout 07:00:29 INFO - Current package versions: 07:00:29 INFO - argparse == 1.2.1 07:00:29 INFO - blessings == 1.6 07:00:29 INFO - blobuploader == 1.2.4 07:00:29 INFO - docopt == 0.6.1 07:00:29 INFO - functools32 == 3.2.3-2 07:00:29 INFO - jsonschema == 2.5.1 07:00:29 INFO - manifestparser == 1.1 07:00:29 INFO - mozInstall == 1.12 07:00:29 INFO - mozcrash == 0.17 07:00:29 INFO - mozdebug == 0.1 07:00:29 INFO - mozdevice == 0.48 07:00:29 INFO - mozfile == 1.2 07:00:29 INFO - mozhttpd == 0.7 07:00:29 INFO - mozinfo == 0.9 07:00:29 INFO - mozleak == 0.1 07:00:29 INFO - mozlog == 3.3 07:00:29 INFO - moznetwork == 0.27 07:00:29 INFO - mozprocess == 0.23 07:00:29 INFO - mozprofile == 0.28 07:00:29 INFO - mozrunner == 6.12 07:00:29 INFO - mozscreenshot == 0.1 07:00:29 INFO - mozsystemmonitor == 0.3 07:00:29 INFO - moztest == 0.7 07:00:29 INFO - mozversion == 1.4 07:00:29 INFO - psutil == 3.1.1 07:00:29 INFO - requests == 1.2.3 07:00:29 INFO - wsgiref == 0.1.2 07:00:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 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 0x1700030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bf3b0>, '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 0x19c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c0740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', '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:29 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:29 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:29 INFO - Using env: {'DISPLAY': ':0', 07:00:29 INFO - 'HOME': '/home/cltbld', 07:00:29 INFO - 'LANG': 'en_US.UTF-8', 07:00:29 INFO - 'LANGUAGE': 'en_US:en', 07:00:29 INFO - 'LOGNAME': 'cltbld', 07:00:29 INFO - 'MAIL': '/var/mail/cltbld', 07:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:29 INFO - 'MOZ_NO_REMOTE': '1', 07:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:29 INFO - 'NO_EM_RESTART': '1', 07:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:29 INFO - 'PWD': '/builds/slave/test', 07:00:29 INFO - 'SHELL': '/bin/bash', 07:00:29 INFO - 'SHLVL': '1', 07:00:29 INFO - 'TERM': 'linux', 07:00:29 INFO - 'TMOUT': '86400', 07:00:29 INFO - 'USER': 'cltbld', 07:00:29 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', 07:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:29 INFO - '_': '/tools/buildbot/bin/python'} 07:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:29 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:29 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:29 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:29 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:29 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:29 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:29 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:29 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:29 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:29 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:29 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:29 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:29 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 0x1700030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19bf3b0>, '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 0x19c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c0740>, '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': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', '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:29 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:29 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:29 INFO - Using env: {'DISPLAY': ':0', 07:00:29 INFO - 'HOME': '/home/cltbld', 07:00:29 INFO - 'LANG': 'en_US.UTF-8', 07:00:29 INFO - 'LANGUAGE': 'en_US:en', 07:00:29 INFO - 'LOGNAME': 'cltbld', 07:00:29 INFO - 'MAIL': '/var/mail/cltbld', 07:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:29 INFO - 'MOZ_NO_REMOTE': '1', 07:00:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:29 INFO - 'NO_EM_RESTART': '1', 07:00:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:29 INFO - 'PWD': '/builds/slave/test', 07:00:29 INFO - 'SHELL': '/bin/bash', 07:00:29 INFO - 'SHLVL': '1', 07:00:29 INFO - 'TERM': 'linux', 07:00:29 INFO - 'TMOUT': '86400', 07:00:29 INFO - 'USER': 'cltbld', 07:00:29 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', 07:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:29 INFO - '_': '/tools/buildbot/bin/python'} 07:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:00:29 INFO - Cleaning up... 07:00:29 INFO - Return code: 0 07:00:29 INFO - Running post-action listener: _resource_record_post_action 07:00:29 INFO - Running post-action listener: _start_resource_monitoring 07:00:29 INFO - Starting resource monitoring. 07:00:29 INFO - [mozharness: 2016-09-01 14:00:29.726721Z] Finished create-virtualenv step (success) 07:00:29 INFO - [mozharness: 2016-09-01 14:00:29.727187Z] Running install step. 07:00:29 INFO - Running pre-action listener: _resource_record_pre_action 07:00:29 INFO - Running main action method: install 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:00:29 INFO - Reading from file tmpfile_stdout 07:00:29 INFO - Detecting whether we're running mozinstall >=1.0... 07:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:00:30 INFO - Reading from file tmpfile_stdout 07:00:30 INFO - Output received: 07:00:30 INFO - Usage: mozinstall [options] installer 07:00:30 INFO - Options: 07:00:30 INFO - -h, --help show this help message and exit 07:00:30 INFO - -d DEST, --destination=DEST 07:00:30 INFO - Directory to install application into. [default: 07:00:30 INFO - "/builds/slave/test"] 07:00:30 INFO - --app=APP Application being installed. [default: firefox] 07:00:30 INFO - mkdir: /builds/slave/test/build/application 07:00:30 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:30 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:46 INFO - Reading from file tmpfile_stdout 07:00:46 INFO - Output received: 07:00:46 INFO - /builds/slave/test/build/application/firefox/firefox 07:00:46 INFO - Running post-action listener: _resource_record_post_action 07:00:46 INFO - [mozharness: 2016-09-01 14:00:46.936990Z] Finished install step (success) 07:00:46 INFO - [mozharness: 2016-09-01 14:00:46.937267Z] Running run-tests step. 07:00:46 INFO - Running pre-action listener: _resource_record_pre_action 07:00:46 INFO - Running main action method: run_tests 07:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:00:46 INFO - Python 2.7.3 07:00:46 INFO - Return code: 0 07:00:46 INFO - grabbing minidump binary from tooltool 07:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:46 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 0x19bf3b0>, '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 0x19c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19c0740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:00:46 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:46 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:46 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:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:00:47 INFO - Return code: 0 07:00:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:00:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:00:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:00:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:00:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:00:47 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-064', '--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:47 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-064 --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:47 INFO - Using env: {'DISPLAY': ':0', 07:00:47 INFO - 'HOME': '/home/cltbld', 07:00:47 INFO - 'LANG': 'en_US.UTF-8', 07:00:47 INFO - 'LANGUAGE': 'en_US:en', 07:00:47 INFO - 'LOGNAME': 'cltbld', 07:00:47 INFO - 'MAIL': '/var/mail/cltbld', 07:00:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:00:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:00:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:47 INFO - 'MOZ_NO_REMOTE': '1', 07:00:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:00:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:47 INFO - 'NO_EM_RESTART': '1', 07:00:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:47 INFO - 'PWD': '/builds/slave/test', 07:00:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:00:47 INFO - 'SHELL': '/bin/bash', 07:00:47 INFO - 'SHLVL': '1', 07:00:47 INFO - 'TERM': 'linux', 07:00:47 INFO - 'TMOUT': '86400', 07:00:47 INFO - 'USER': 'cltbld', 07:00:47 INFO - 'XDG_SESSION_COOKIE': 'a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848', 07:00:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:47 INFO - '_': '/tools/buildbot/bin/python'} 07:00:47 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-064', '--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:47 INFO - mozversion application_buildid: 20160830104522 07:00:47 INFO - mozversion application_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:47 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:00:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:00:47 INFO - mozversion application_name: Firefox 07:00:47 INFO - mozversion application_remotingname: firefox-dev 07:00:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:47 INFO - mozversion application_vendor: Mozilla 07:00:47 INFO - mozversion application_version: 50.0a2 07:00:47 INFO - mozversion platform_buildid: 20160830104522 07:00:47 INFO - mozversion platform_changeset: bac8c738419eb9ff1fe0947120e879707d19c97b 07:00:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:00:47 INFO - mozversion platform_version: 50.0a2 07:00:47 INFO - using testdate: 1472738447 07:00:47 INFO - actual date: 1472738447 07:00:47 INFO - starting webserver on 'localhost:58561' 07:00:47 INFO - SUITE-START | Running 2 tests 07:00:47 INFO - TEST-START | dromaeo_css 07:00:47 INFO - operating with platform_type : linux_ 07:00:47 INFO - Initialising browser for dromaeo_css test... 07:00:47 INFO - TEST-INFO | started process 18229 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprCzCsI/profile http://localhost:58561/getInfo.html) 07:00:51 INFO - PROCESS | 18229 | __metrics Screen width/height:1600/1200 07:00:51 INFO - PROCESS | 18229 | colorDepth:24 07:00:51 INFO - PROCESS | 18229 | Browser inner width/height: 1024/697 07:00:51 INFO - PROCESS | 18229 | __metrics 07:00:52 INFO - PROCESS | 18229 | 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:52 INFO - PROCESS | 18229 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:00:53 INFO - TEST-INFO | 18229: exit 0 07:00:53 INFO - Browser initialized. 07:00:53 INFO - Running cycle 1/1 for dromaeo_css test... 07:00:53 INFO - TEST-INFO | started process 18319 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprCzCsI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:01:05 INFO - PROCESS | 18319 | RSS: Main: 144916480 07:01:05 INFO - PROCESS | 18319 | 07:01:56 INFO - PROCESS | 18319 | 1472738516169 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:05 INFO - PROCESS | 18319 | Cycle 1(1): loaded http://localhost:58561/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58561/tests/dromaeo/cssquery-ext.html) 07:04:06 INFO - PROCESS | 18319 | RSS: Main: 278585344 07:04:06 INFO - PROCESS | 18319 | 07:07:09 INFO - PROCESS | 18319 | Cycle 1(1): loaded http://localhost:58561/tests/dromaeo/cssquery-ext.html (next: http://localhost:58561/tests/dromaeo/cssquery-jquery.html) 07:07:09 INFO - PROCESS | 18319 | RSS: Main: 234967040 07:07:09 INFO - PROCESS | 18319 | 07:10:11 INFO - PROCESS | 18319 | Cycle 1(1): loaded http://localhost:58561/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58561/tests/dromaeo/cssquery-mootools.html) 07:10:12 INFO - PROCESS | 18319 | RSS: Main: 216961024 07:10:12 INFO - PROCESS | 18319 | 07:13:13 INFO - PROCESS | 18319 | Cycle 1(1): loaded http://localhost:58561/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58561/tests/dromaeo/cssquery-prototype.html) 07:13:14 INFO - PROCESS | 18319 | RSS: Main: 227926016 07:13:14 INFO - PROCESS | 18319 | 07:16:17 INFO - PROCESS | 18319 | Cycle 1(1): loaded http://localhost:58561/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58561/tests/dromaeo/cssquery-yui.html) 07:16:17 INFO - PROCESS | 18319 | RSS: Main: 176168960 07:16:17 INFO - PROCESS | 18319 | 07:19:20 INFO - PROCESS | 18319 | Cycle 1(1): loaded http://localhost:58561/tests/dromaeo/cssquery-yui.html (next: http://localhost:58561/tests/dromaeo/cssquery-dojo.html) 07:19:20 INFO - PROCESS | 18319 | RSS: Main: 264687616 07:19:20 INFO - PROCESS | 18319 | 07:19:20 INFO - PROCESS | 18319 | __start_tp_report 07:19:20 INFO - PROCESS | 18319 | _x_x_mozilla_page_load 07:19:20 INFO - PROCESS | 18319 | _x_x_mozilla_page_load_details 07:19:20 INFO - PROCESS | 18319 | |i|pagename|runs| 07:19:20 INFO - PROCESS | 18319 | |0;dojo.html;3204.7436205103595;3243.691849274318;3254.8535315910776;3262.918427039322;3297.7361811055102;5641.012822756016;5758.539316854647;5765.7981970631035;5772.076467765159;5782.335031471382;5722.284714410699;5782.653040817554;5814.941850581493;5815.62198457101;5825.126231065342;5078.31442755227;5187.688738675692;5267.078261304266;5268.236105764662;5321.973390133044;5254.474552544734;5298.894022119555;5304.283921670583;5305.6551324164;5308.283381743454;5169.302144210521;5185.637005409623;5203.0374380739495;5203.895922081575;5289.920651190216;5303.787848486075;5306.416294207673;5313;5315.388730296027;5347.0909945309395;5081.441041485434;5149.794008239666;5179.896402071938;5208.557272631793;5208.5572726318305;5575.135853942646;5606.299212598426;5614.214010038598;5624.803131890364;5649.887002259932;6097.80419217806;6102.8779424411705;6109.602875813058;6112.651429998751;6117.62829776842;6050.45545900871;6128.264608247039;6143.262808463013;6164.568480206387;6188.504919606421;5775.1337299405;5777.335606405275;5786.739596718157;5787.392323806024;5788.594798364116;6326.557141000086;6337.366263373718;6341.143945567289;6342.23893132827;6345.714442850082;5880.852978675567;5966.582339236299;6022.909656355158;6030.638161710223;6085.073549543254;6203.31763506014;6206.782762603287;6210.658413787195;6213.316535181127;6220.377962203744;6097.8475538111015;6144.56988010835;6160.784372546939;6165.722542485599;6168.9691551541955;5957.314908785545;6009.669468179205;6045.455908968126;6095;6167.938320616826;4657.324687920301;4693.413323334583;4697.600703940203;4723.504032076651;4746.667733258639;4163.958360416417;4178.247915375262;4178.791060446965;4190.455240818674;4203.306454435045;5669.262995810518;5709.25779648643;5735.283089613798;5755.395683453177;5778.046622307356;6166.9383306167265;6174.938250617526;6177.96911015442;6180.196574445294;6183.227096612924;5805.390434004453;5808.564357673192;5831.241938548046;5837.620554663933;5850.297964244318;5166.61250406221;5267.604929630293;5272.92090618641;5322.707251101227;5332.866678333073;6375.872482550323;6436.356364363526;6457.031445283151;6460.321666224979;6463.7091330890225;6234.906476402767;6253.249610046823;6255.437010669061;6260.154879091265;6263.311035785969;6210.472109870531;6281.685915704105;6315.526335524946;6351.047342898602;6461.224653041665;6070.939290607214;6079.544034197541;6083.6045657032155;6088.3911608840535;6121.785737499166;6194.504439644727;6207.193064901715;6214.937850621617;6225.501959843292;6231.594946328474;5882.588218824641;5966.194563734014;6002.6398416093425;6006.459418652343;6077.756889724362;5990.550708696778;6029.728662210124;6030.969845150747;6035.849103772441;6055.212822333068;5980.19267398913;6003.849903752266;6023.849403764941;6044.032954727399;6046.637201767908;5912.526997840248;5943.821685349447;5998.25021872266;6006.039033754579;6098.634081955097;5887.381824908494;5892.9116063258225;5896.852578685567;5900.940990590039;5920.585559011 07:19:20 INFO - PROCESS | 18319 | |1;ext.html;598.8413070536305;621.434494609905;667.4760313154175;671.6809515480151;685.1777866560129;34158;37336.439953400724;37380;37433.06417339564;37433.625663743354;39199.21601567967;39513.802430987875;40057.59942400575;47593.524064759345;49312;9095.545222738852;9202.079792020812;9215.907840921573;9216.861747073799;9218.723438296829;9095;9181.632734690638;9191.95404022979;9192.954035229814;9195.999999999967;8662.653493860238;9178.082191780803;11066;11376.943115284412;11388.088952883749;11211.943940280327;11264.324140551593;11278.830817537744;11288.209825312273;11288.322700637988;11061.944690276578;11088.611898583511;11120.165987550967;11120.61077862279;11145.665630031031;35115.473267901005;44269;45423.77288113538;45838;48790.512094878955;3994.3409337459284;4106.527749308838;4176.436181115571;4282.743035417885;4534.773261336953;34223.31553368919;34298.31403371919;34367.484487732705;34507;34829;4069.3285607874664;4096.631303182728;4112.527059388179;4117.197146556423;4216.2832318505925;374990;375545;375725;375978;376281.11859440804;58563.414365856646;63504;65037.67481162659;65258.67370663116;65608.34391656118;62448.37551624516;67771.32228677749;67800.6609966947;67878.32121678819;67956.6602166986;19313.613727725366;19589.216431342586;19593.510162245773;19601.1179496923;19611.117499712545;14758.335874885655;14937.850621493862;14942.327595258239;14944.551663450115;14962.551123466314;17062.658746824993;18974.62050758977;18997.47637191356;19105.90447047756;19346.613067738846;5588.590959514693;6489.383508566679;6584.9012264816065;6703.564268322543;6716.630585317855;982.9115379615725;1050.5325130317087;1120.708615759892;1227.110344999868;1239.9504019839285;35123.473147902805;35206;35660.46509302311;35802.64197358045;35927.4610880837;1139.0261326565833;1143.228320883417;1151.4357964597286;1151.5796734192127;1177.5996161305197;18308.267669293346;18614.906925465555;18646;18688.439346819618;18714.438566843015;515594;516643;517793;518928;521277;57411.425885741446;61638;63146.68426657837;64218.35781642124;64407;1461.4446510326009;1496.7231062253518;1498.9850101499283;1500.2948614151549;1501.204361688307;34919.65080349164;36101.277974439836;36385.61438561438;36680.44979325259;36789;56878.43121568731;57602;57667.423325767886;57674.71162644328;57702.7114864423;515576;528497;529300;776367;777665;29967.550486743152;30111.397772043998;30296.84851575728;30326.545101823933;30620.387592248477;51777;52052.739736301075;52402;52702.20946685726;53502.46497535131;42651.7867410661;43950.78024609984;44527.332090018026;44663.77668111639;45329;43717.56282437135;44488.55511444844;44922.10155796931;45356.09287814159;45995.77002114968;43984.34023489586;44608.10783784371;45112.54887451215;45312.54687453083;45372;36135.63864361323;39128.413073804484;39370.212595747354;39478.802605986784;39814.2037159261;30140;30266;30282.394352113275;30285.394292114474;30645.846770766002 07:19:20 INFO - PROCESS | 18319 | |2;jquery.html;397.51304651801547;400.7635495057919;410.70018886213063;414.5779974686825;421.928272193727;14739.48411805587;20317;20851;20893.47766305843;21451.141954321807;20520.589588208266;20548.280810171647;20558.280460183858;20587.794122058775;20846;5644.491995720385;5680.9715951420185;5809.331926828406;5841.591088623798;5845.85385365365;5694.259746232984;5740.282464691913;5740.827775166752;5761.625494342854;5762.7406766695385;5677.261955945722;5792.275965504312;5827.388124246957;5831.737571809257;5832.737526811304;5607.719614019283;5781.075027995522;5807.796727114531;5808.04167312395;5826.009578371666;5183.663061900965;5217.3999990000875;5219.451957544479;5220.556252718486;5223.660462069954;24112.75887241123;24285.878570607212;24330.14844480435;24545.754542454524;24578.877105614538;2389.438481956739;2438.5366780311683;2475.9009639614574;2789.205076553193;2899.5795609636693;11206.439678016148;11394.772104557862;14513.984021118526;15477.148756818371;16226.756598651029;2095.8847263400507;2102.2747152232455;2120.300300900699;2144.281665642012;2207.4702071502884;84984;86172;87755.56122219411;88479.11520884772;90467;9759.65841195569;10214.591416343412;10314.48427578618;10407.47962601882;11527.538898444136;13876.653083672989;13889.79165312521;13892.16647001183;13929.373178207004;13937.442502299795;8310.382342275168;8373.330133589297;8494.957525212334;8517.701880434155;8716.956415217883;8337.207965243293;8375.157465357612;8445.619947102272;8518.70184543538;8538.103499132625;4351.129774045204;4370.344448332776;4389.078293558388;4390;4416;1985.146387053552;2020.2525065725777;2026.8885211313225;2053.9486512837;2062.1339037604243;986.3982970388057;993.4734590666958;1018.2871989607305;1058.1059005140644;1074.8925107489188;20330.491737706674;20523;21241.575168496543;21401.46496337573;21702.674459883114;978.8678528398575;1014.4927536231884;1042.0829669890447;1094.3269889018356;1159.0611604600301;11270.267432616854;11642.185047046794;11734.882651173379;11913.761724765629;11920.761584768255;437834;449693;519165;526731;539707;92832.53583732038;94439;95029;95623;97716.51141744245;1035.7876635289747;1065.9893401066045;1072.3137192197;1083.8482612434266;1086.1256688365784;18257.817421826145;18506.44480665582;18782.15480303417;18917.90541047341;18922;46515;46542.30186547272;46707;46988.53011469841;47817.52182478131;62337.376626233156;63093.68453157705;63692.68153659202;64115.35884641094;65645.67177164083;8801.07588703177;8809.42738721981;9014.23379012765;9117.452952822852;9218.354715170142;12484.313362765135;13192.076554641082;13229.47082116743;13554.18674879471;13657.38541765603;12443.191192572454;12477.126601137834;12765.93617031909;12779.23324600527;12783.552575659807;12195.390230488618;12222.327771972627;12389.070819688739;12413.565525206574;12839.165454245443;11951.521939122338;11956.163068585116;12337.691557711129;12406.56577019836;12544.372781360713;8814.779630509289;8989.865152022594;9015.459072455673;9103.954480227556;9283.210927071275;9189.678361257591;11616.303021818718;12108.818367724667;12125.333106679218;12125.514979400725 07:19:20 INFO - PROCESS | 18319 | |3;mootools.html;1435.5119259451785;1584.7622856571516;1626.2031604513788;1826.3151318255655;1929.7298378227051;9188.908110918888;9477.052294770529;9577.185939195168;9690;9700.175485083759;9760.804783904317;9894.15899648529;9895.455749933752;9905;9906.108450239477;2542.7584379483965;2564.3973666188526;2568.0883286433327;2568.4863027394504;2569.9486010279784;2523;2565.384307766132;2565.8460492370423;2566.461043180925;2570.0747730816984;2486.191987604027;2492.663490428787;2493.4265119022543;2499.7750202481775;2534.4424226670103;2412.8190385721055;2417.093589903786;2428.1258746851117;2431.100492817669;2453.6564880916685;2403.903843846244;2408.277516744987;2444.278937713379;2448.1431498975394;2451.3994071452607;9549.904500954972;9690.079442452956;9811;9811.509424528746;9832.705018849374;5582.413846546136;5753.424657534255;5758.88482230353;5770.971145144289;5803.187553742479;3489.2672538766888;3507.982460087683;3550.786952782841;3589.46158076288;3594.6764791168657;1128.9379084150369;1133.471205854277;1147.9655610331704;1156.121347775688;1163.7497937943383;13289.534866279633;13292.73414531704;13344.332783360793;13350.933245333808;13391.129576577492;4883.682560633548;4894.045661096124;4976.527229913152;5056.26684130799;5105.846824595268;10949.288296260718;10959.83560246597;10975.725606859733;10979;10982.780344393068;5774.509166720875;5775.191473193756;5778.508826749773;5786.797462088806;5798.9130163047585;4611.861644150681;4696.204300860555;4779.474257831635;5115;5392.40683524812;4061.6953728470485;4098.959010409858;4157.8336866525;4162.771047592351;4195.979020104941;1234.1977714485656;1267.352441825623;1280.9359532023361;1287.0990306785288;1295.1257900916844;1086.375334182848;1096.1723898456614;1106.0819519798547;1108.9611863584735;1119.5018216893404;6293.559450838397;6500.6424646643945;6720.462363010947;6843.863122737517;6915.377616014583;495.45499950054653;501.93725784276967;504.12031005894215;509.6508891409396;521.0698902459093;4487.259602165606;4660.743659098782;4665.46347170073;4673.579377856009;4729.527047295243;11642;11854.258296681397;12132.45403956824;12175.512979480898;12199.451024703909;11899.702507437383;12101.394930253453;12105.152639315163;12175.452104655134;12177.93911030457;9180.10826589622;9223.44659320443;9338.252939764803;9374.67188648394;9435.528223588793;6672.165979252593;6785.117934668461;6846.726130954905;6848;6865.485088618274;10919.727006824893;11316.434178291218;11410.087193024372;11410.201285910238;11484.138689598147;11094.278871873303;11133.88866111361;11324.830127548259;11349.319040857576;11494.195406321478;7825.647845847065;7835.686572537034;7852.097008844056;7860.174681658572;7935.444518883623;9189.678361257324;9273.814523709623;9274.675386361445;9277.443353398818;9282.118198771106;7941.205879412013;8294.875576866472;8372.665093396196;8554.91445085541;8720.78198045054;8242.332540290814;8246.793830154294;8310.70912518059;8345.95827020861;8414.495130291958;7106.751263705745;7304.233055529306;7374;7754.728584499516;7809.609519523888;5615.438456154352;5830.2129212555765;5864.384239654945;5865.94134058654;6058.606190597597;5657.717114144194;5757.7121143941795;5769.1634712967225;5784.2769653793275;5945.970270148795 07:19:20 INFO - PROCESS | 18319 | |4;prototype.html;340.59979524059224;350.14040530497624;356.3549974546073;356.9214772749996;362.38575614333706;5459;5502.80740174094;5509.917351239735;5549.528290095342;5557.527610153132;5437.592180586452;5446.747248132931;5449.618526703132;5451.918221226675;5480.301106656939;4054.869383542181;4319.913601727963;4340.348947657845;4387.5173730889655;4397.824087036531;3973.46358241638;4280.208161490119;4280.6361459275995;4308.82764689412;4324.345927691096;4270.957290427103;4279.587736047112;4281.079567892903;4283.37891005804;4304.268274393345;4099.487564054493;4266.997255644921;4291.399204111427;4321.783910804447;4326.918270432408;3633.6911362534215;3786.727963222072;3793.6016718244464;3803.581606023335;3823.388257878726;5306.628536002481;5315.335583052119;5319.5478384337375;5328.600354973354;5348.973255133699;4645.442546894395;4893.290472881412;4900.534449227318;4930.211166213388;4946.233333833262;5687.435955112336;5690.2033715279895;5694.9430505695245;5715.2855893013375;5717.942820571782;4151.771652559108;4155.771432571208;4158.958410415888;4174.436451079096;4178;5785.421457854223;5894;5947.51312171957;6022.638641681513;6036.456718895321;4261.659067274611;4264.594863487963;4273.145370925828;4282.9785851071165;4284.764337961442;4291.957080429218;4300.935485967713;4312.935305970413;4321.416608757841;4333.631641310524;4645.86062418128;4888.5111488850835;4917.975410122998;4918.1884988977135;4954.677945933503;5717.199592057115;5748.016934157709;5764.279465066867;5768;5793;1473.1823837770141;1492.3434987164455;1495.6410461489381;1503.9096654925092;1505.0969418348902;904.3244498692862;941.5715849288557;942.4392486470676;943.7263193673758;960.6493629825063;4901.926471102936;4939.234418665041;4958.380202474691;5288.720992984374;5441.340391180649;5880.970595147082;5897.7935772247765;5908.586398952118;5928.525717942554;5953.732082056318;5591.909577632323;5612.579056570774;5641.097424412074;5651.4348565143155;5654.604177707603;3049.405365953662;3131.42068717288;3152.511360739089;3153.44814657434;3167.5270998985584;6107.389261073857;6128.693565321716;6130.049842274455;6134.969325153345;6150.661713605795;5722.37053924068;5929.466348028698;5981.401859813984;6003.459688627957;6065.423784740443;6056.485198758067;6057.969710151421;6095.786647467317;6096.3903609639565;6129.080637904171;5748.597598168255;5888.941110588839;5897.203877476486;5954.37479064692;5996.910046349221;5836.503897168788;5845.4801751544055;5867.237259156283;5871.706414679335;5889.617174883619;5982.760689572368;6111.297200821962;6124.448799608057;6125.754969801158;6129.570930034854;3987.720859539804;4149.813258403318;4157.355609880473;4166.729162604421;4184.91630167401;5825.155352473952;5864.9706751467675;5880.088586269135;5886.676232807236;5890.263717035371;5707.086866101404;5744.425557444222;5755.482006619424;5763.9135412968;5766.798162064308;5570.721463926869;5753.884922301614;5758.8848223034465;5759.9136012959;5813.680247586254;5718.685472298897;5758.424157584208;5761.625494342854;5761.654300741969;5765.4522820332;5395.271638328933;5675.68783716883;5792.652440853562;5834.883302334015;5847.8245652630485;5552.916706249491;5599.628091117702;5688.260526131577;5688.971555142198;5691.971540142273 07:19:20 INFO - PROCESS | 18319 | |5;yui.html;814.5031530766231;819.2544784246334;827.0365024746171;853.8676505141701;859.5959898847543;15779;16392.672146557063;16533.834661653378;16583.66832663346;16618;16144.83855161448;16444.588885277873;16479.093649849252;16498.25757840897;16500.83499165008;732.0702707561394;848.1476116502906;863.6804382378529;865.0959747064302;869.9434536755107;871.3464901324007;872.2062922740307;872.8472517309477;872.8472517309477;873.0483772687769;928.6007016982695;928.8281667891428;928.9256859451227;929.0802105915129;929.419113054341;767.8272388712551;895.3642913531401;920.8987011428737;937.8265020971143;941.6751220828803;698.7274962764526;698.800841760101;698.8567343694581;699.098163369253;699.248308068828;15799.368025278975;15810;15819.920900395424;15857.286422111032;15894.602634934103;14838.25808709571;15174.24128793556;15244.161571113584;15321.540353789293;15570.610734731608;13832.654183645389;13994.510192143227;14229.359678814479;14367.353469093916;14402.279886005657;4548.636109111296;4563.612092972101;4636.3045543168455;4684.414448193976;4710.811567537295;88159.11840881573;88885.55557222173;89703.55148224217;89731.55134224288;89757.55121224352;11843.703907402383;12397.690057748629;12461.12772105962;12461.813072803916;12592.118551701476;23098.884505577364;23143;23202.76797232006;23244.41888952756;23974.160904368262;9155.81688366229;9156.404833685789;9184;9205.539723013957;9249.907500924905;4987.925181122286;5137.306463627438;5138.486151384832;5171.5862730981435;5318.175682769177;3730.720195985312;3861.5366156061455;3877.747946383476;3879.786611736325;3890.1247219375414;651.5960104735094;654.5189285874878;657.7533424965637;658.9176352955881;671.9529632925746;698.7763915546976;722.8482018776011;759.7682706774376;770.2836362183111;793.4564823096198;9486.857697134548;9780.804383912282;9844.704658860246;9929.851052234222;10346;720.1753991679498;728.6101935464501;728.65024788102;730.8940203670438;790.4190420041265;4436.689431739747;4546.749928753884;4599.379083823708;4636.420447444069;4640.83757068501;102528.48735756273;129254;130035;130195;133339;73734;98326.5083674577;98398.50800746094;99076;99357.50321248347;53929.46070539323;55288.17067743987;86735;88578;90013;10367.844482332923;10464.790704186025;10503.05472507478;10571.788564228826;10677.626283080055;37727.434088488146;49810.25284620661;52274.21588676097;53273.7336313316;55696;74042;74157.2584274172;74585.62707186429;77601.2239877594;78893.21106788858;14064.296785160906;14085.788713169515;14087.295635218403;14553.781693274821;14917.403303867724;16344.264508097402;16866.578335541708;16936.915315423343;16944.40694575684;17057.232424541173;15337.386504539694;15399.846001540289;15912.92043539775;16053;16632.916835415745;14043;14282.785758213842;14284.42862285497;15030.849691503383;15700.371985120468;14109.576712698636;14995.850041499882;15117.54647360581;15578.376864925276;16584.66830663404;12012.219205751599;12547.62357129251;12776.425060872098;12809.167404118702;13010.609681709564;11969.64091077269;12331.568395106127;12386.194897331645;12598;12944.870551294744 07:19:20 INFO - PROCESS | 18319 | __end_tp_report 07:19:20 INFO - PROCESS | 18319 | __start_cc_report 07:19:20 INFO - PROCESS | 18319 | _x_x_mozilla_cycle_collect,1151 07:19:20 INFO - PROCESS | 18319 | __end_cc_report 07:19:20 INFO - PROCESS | 18319 | __startTimestamp1472739560690__endTimestamp 07:19:20 INFO - PROCESS | 18319 | 07:19:20 INFO - PROCESS | 18319 | ------- Summary: start ------- 07:19:20 INFO - PROCESS | 18319 | Number of tests: 6 07:19:20 INFO - PROCESS | 18319 | 07:19:20 INFO - PROCESS | 18319 | [#0] dojo.html Cycles:165 Average:5713.00 Median:5920.59 stddev:661.67 (11.2%) stddev-sans-first:633.77 07:19:20 INFO - PROCESS | 18319 | Values: 3204.7 3243.7 3254.9 3262.9 3297.7 5641.0 5758.5 5765.8 5772.1 5782.3 5722.3 5782.7 5814.9 5815.6 5825.1 5078.3 5187.7 5267.1 5268.2 5322.0 5254.5 5298.9 5304.3 5305.7 5308.3 5169.3 5185.6 5203.0 5203.9 5289.9 5303.8 5306.4 5313.0 5315.4 5347.1 5081.4 5149.8 5179.9 5208.6 5208.6 5575.1 5606.3 5614.2 5624.8 5649.9 6097.8 6102.9 6109.6 6112.7 6117.6 6050.5 6128.3 6143.3 6164.6 6188.5 5775.1 5777.3 5786.7 5787.4 5788.6 6326.6 6337.4 6341.1 6342.2 6345.7 5880.9 5966.6 6022.9 6030.6 6085.1 6203.3 6206.8 6210.7 6213.3 6220.4 6097.8 6144.6 6160.8 6165.7 6169.0 5957.3 6009.7 6045.5 6095.0 6167.9 4657.3 4693.4 4697.6 4723.5 4746.7 4164.0 4178.2 4178.8 4190.5 4203.3 5669.3 5709.3 5735.3 5755.4 5778.0 6166.9 6174.9 6178.0 6180.2 6183.2 5805.4 5808.6 5831.2 5837.6 5850.3 5166.6 5267.6 5272.9 5322.7 5332.9 6375.9 6436.4 6457.0 6460.3 6463.7 6234.9 6253.2 6255.4 6260.2 6263.3 6210.5 6281.7 6315.5 6351.0 6461.2 6070.9 6079.5 6083.6 6088.4 6121.8 6194.5 6207.2 6214.9 6225.5 6231.6 5882.6 5966.2 6002.6 6006.5 6077.8 5990.6 6029.7 6031.0 6035.8 6055.2 5980.2 6003.8 6023.8 6044.0 6046.6 5912.5 5943.8 5998.3 6006.0 6098.6 5887.4 5892.9 5896.9 5900.9 5920.6 07:19:20 INFO - PROCESS | 18319 | 07:19:20 INFO - PROCESS | 18319 | [#1] ext.html Cycles:180 Average:67418.03 Median:22025.97 stddev:138939.09 (630.8%) stddev-sans-first:139238.28 07:19:20 INFO - PROCESS | 18319 | Values: 598.8 621.4 667.5 671.7 685.2 34158.0 37336.4 37380.0 37433.1 37433.6 39199.2 39513.8 40057.6 47593.5 49312.0 9095.5 9202.1 9215.9 9216.9 9218.7 9095.0 9181.6 9192.0 9193.0 9196.0 8662.7 9178.1 11066.0 11376.9 11388.1 11211.9 11264.3 11278.8 11288.2 11288.3 11061.9 11088.6 11120.2 11120.6 11145.7 35115.5 44269.0 45423.8 45838.0 48790.5 3994.3 4106.5 4176.4 4282.7 4534.8 34223.3 34298.3 34367.5 34507.0 34829.0 4069.3 4096.6 4112.5 4117.2 4216.3 374990.0 375545.0 375725.0 375978.0 376281.1 58563.4 63504.0 65037.7 65258.7 65608.3 62448.4 67771.3 67800.7 67878.3 67956.7 19313.6 19589.2 19593.5 19601.1 19611.1 14758.3 14937.9 14942.3 14944.6 14962.6 17062.7 18974.6 18997.5 19105.9 19346.6 5588.6 6489.4 6584.9 6703.6 6716.6 982.9 1050.5 1120.7 1227.1 1240.0 35123.5 35206.0 35660.5 35802.6 35927.5 1139.0 1143.2 1151.4 1151.6 1177.6 18308.3 18614.9 18646.0 18688.4 18714.4 515594.0 516643.0 517793.0 518928.0 521277.0 57411.4 61638.0 63146.7 64218.4 64407.0 1461.4 1496.7 1499.0 1500.3 1501.2 34919.7 36101.3 36385.6 36680.4 36789.0 56878.4 57602.0 57667.4 57674.7 57702.7 515576.0 528497.0 529300.0 776367.0 777665.0 29967.6 30111.4 30296.8 30326.5 30620.4 51777.0 52052.7 52402.0 52702.2 53502.5 42651.8 43950.8 44527.3 44663.8 45329.0 43717.6 44488.6 44922.1 45356.1 45995.8 43984.3 44608.1 45112.5 45312.5 45372.0 36135.6 39128.4 39370.2 39478.8 39814.2 30140.0 30266.0 30282.4 30285.4 30645.8 07:19:20 INFO - PROCESS | 18319 | 07:19:20 INFO - PROCESS | 18319 | [#2] jquery.html Cycles:180 Average:30057.69 Median:24562.32 stddev:82050.86 (334.1%) stddev-sans-first:82250.81 07:19:20 INFO - PROCESS | 18319 | Values: 397.5 400.8 410.7 414.6 421.9 14739.5 20317.0 20851.0 20893.5 21451.1 20520.6 20548.3 20558.3 20587.8 20846.0 5644.5 5681.0 5809.3 5841.6 5845.9 5694.3 5740.3 5740.8 5761.6 5762.7 5677.3 5792.3 5827.4 5831.7 5832.7 5607.7 5781.1 5807.8 5808.0 5826.0 5183.7 5217.4 5219.5 5220.6 5223.7 24112.8 24285.9 24330.1 24545.8 24578.9 2389.4 2438.5 2475.9 2789.2 2899.6 11206.4 11394.8 14514.0 15477.1 16226.8 2095.9 2102.3 2120.3 2144.3 2207.5 84984.0 86172.0 87755.6 88479.1 90467.0 9759.7 10214.6 10314.5 10407.5 11527.5 13876.7 13889.8 13892.2 13929.4 13937.4 8310.4 8373.3 8495.0 8517.7 8717.0 8337.2 8375.2 8445.6 8518.7 8538.1 4351.1 4370.3 4389.1 4390.0 4416.0 1985.1 2020.3 2026.9 2053.9 2062.1 986.4 993.5 1018.3 1058.1 1074.9 20330.5 20523.0 21241.6 21401.5 21702.7 978.9 1014.5 1042.1 1094.3 1159.1 11270.3 11642.2 11734.9 11913.8 11920.8 437834.0 449693.0 519165.0 526731.0 539707.0 92832.5 94439.0 95029.0 95623.0 97716.5 1035.8 1066.0 1072.3 1083.8 1086.1 18257.8 18506.4 18782.2 18917.9 18922.0 46515.0 46542.3 46707.0 46988.5 47817.5 62337.4 63093.7 63692.7 64115.4 65645.7 8801.1 8809.4 9014.2 9117.5 9218.4 12484.3 13192.1 13229.5 13554.2 13657.4 12443.2 12477.1 12765.9 12779.2 12783.6 12195.4 12222.3 12389.1 12413.6 12839.2 11951.5 11956.2 12337.7 12406.6 12544.4 8814.8 8989.9 9015.5 9104.0 9283.2 9189.7 11616.3 12108.8 12125.3 12125.5 07:19:20 INFO - PROCESS | 18319 | 07:19:20 INFO - PROCESS | 18319 | [#3] mootools.html Cycles:180 Average:6325.98 Median:4712.87 stddev:3657.52 (77.6%) stddev-sans-first:3649.31 07:19:20 INFO - PROCESS | 18319 | Values: 1435.5 1584.8 1626.2 1826.3 1929.7 9188.9 9477.1 9577.2 9690.0 9700.2 9760.8 9894.2 9895.5 9905.0 9906.1 2542.8 2564.4 2568.1 2568.5 2569.9 2523.0 2565.4 2565.8 2566.5 2570.1 2486.2 2492.7 2493.4 2499.8 2534.4 2412.8 2417.1 2428.1 2431.1 2453.7 2403.9 2408.3 2444.3 2448.1 2451.4 9549.9 9690.1 9811.0 9811.5 9832.7 5582.4 5753.4 5758.9 5771.0 5803.2 3489.3 3508.0 3550.8 3589.5 3594.7 1128.9 1133.5 1148.0 1156.1 1163.7 13289.5 13292.7 13344.3 13350.9 13391.1 4883.7 4894.0 4976.5 5056.3 5105.8 10949.3 10959.8 10975.7 10979.0 10982.8 5774.5 5775.2 5778.5 5786.8 5798.9 4611.9 4696.2 4779.5 5115.0 5392.4 4061.7 4099.0 4157.8 4162.8 4196.0 1234.2 1267.4 1280.9 1287.1 1295.1 1086.4 1096.2 1106.1 1109.0 1119.5 6293.6 6500.6 6720.5 6843.9 6915.4 495.5 501.9 504.1 509.7 521.1 4487.3 4660.7 4665.5 4673.6 4729.5 11642.0 11854.3 12132.5 12175.5 12199.5 11899.7 12101.4 12105.2 12175.5 12177.9 9180.1 9223.4 9338.3 9374.7 9435.5 6672.2 6785.1 6846.7 6848.0 6865.5 10919.7 11316.4 11410.1 11410.2 11484.1 11094.3 11133.9 11324.8 11349.3 11494.2 7825.6 7835.7 7852.1 7860.2 7935.4 9189.7 9273.8 9274.7 9277.4 9282.1 7941.2 8294.9 8372.7 8554.9 8720.8 8242.3 8246.8 8310.7 8346.0 8414.5 7106.8 7304.2 7374.0 7754.7 7809.6 5615.4 5830.2 5864.4 5865.9 6058.6 5657.7 5757.7 5769.2 5784.3 5946.0 07:19:20 INFO - PROCESS | 18319 | 07:19:20 INFO - PROCESS | 18319 | [#4] prototype.html Cycles:180 Average:4838.25 Median:5555.22 stddev:1422.80 (25.6%) stddev-sans-first:1386.17 07:19:20 INFO - PROCESS | 18319 | Values: 340.6 350.1 356.4 356.9 362.4 5459.0 5502.8 5509.9 5549.5 5557.5 5437.6 5446.7 5449.6 5451.9 5480.3 4054.9 4319.9 4340.3 4387.5 4397.8 3973.5 4280.2 4280.6 4308.8 4324.3 4271.0 4279.6 4281.1 4283.4 4304.3 4099.5 4267.0 4291.4 4321.8 4326.9 3633.7 3786.7 3793.6 3803.6 3823.4 5306.6 5315.3 5319.5 5328.6 5349.0 4645.4 4893.3 4900.5 4930.2 4946.2 5687.4 5690.2 5694.9 5715.3 5717.9 4151.8 4155.8 4159.0 4174.4 4178.0 5785.4 5894.0 5947.5 6022.6 6036.5 4261.7 4264.6 4273.1 4283.0 4284.8 4292.0 4300.9 4312.9 4321.4 4333.6 4645.9 4888.5 4918.0 4918.2 4954.7 5717.2 5748.0 5764.3 5768.0 5793.0 1473.2 1492.3 1495.6 1503.9 1505.1 904.3 941.6 942.4 943.7 960.6 4901.9 4939.2 4958.4 5288.7 5441.3 5881.0 5897.8 5908.6 5928.5 5953.7 5591.9 5612.6 5641.1 5651.4 5654.6 3049.4 3131.4 3152.5 3153.4 3167.5 6107.4 6128.7 6130.0 6135.0 6150.7 5722.4 5929.5 5981.4 6003.5 6065.4 6056.5 6058.0 6095.8 6096.4 6129.1 5748.6 5888.9 5897.2 5954.4 5996.9 5836.5 5845.5 5867.2 5871.7 5889.6 5982.8 6111.3 6124.4 6125.8 6129.6 3987.7 4149.8 4157.4 4166.7 4184.9 5825.2 5865.0 5880.1 5886.7 5890.3 5707.1 5744.4 5755.5 5763.9 5766.8 5570.7 5753.9 5758.9 5759.9 5813.7 5718.7 5758.4 5761.6 5761.7 5765.5 5395.3 5675.7 5792.7 5834.9 5847.8 5552.9 5599.6 5688.3 5689.0 5692.0 07:19:20 INFO - PROCESS | 18319 | 07:19:20 INFO - PROCESS | 18319 | [#5] yui.html Cycles:180 Average:21649.58 Median:4581.50 stddev:30692.99 (669.9%) stddev-sans-first:30739.22 07:19:20 INFO - PROCESS | 18319 | Values: 814.5 819.3 827.0 853.9 859.6 15779.0 16392.7 16533.8 16583.7 16618.0 16144.8 16444.6 16479.1 16498.3 16500.8 732.1 848.1 863.7 865.1 869.9 871.3 872.2 872.8 872.8 873.0 928.6 928.8 928.9 929.1 929.4 767.8 895.4 920.9 937.8 941.7 698.7 698.8 698.9 699.1 699.2 15799.4 15810.0 15819.9 15857.3 15894.6 14838.3 15174.2 15244.2 15321.5 15570.6 13832.7 13994.5 14229.4 14367.4 14402.3 4548.6 4563.6 4636.3 4684.4 4710.8 88159.1 88885.6 89703.6 89731.6 89757.6 11843.7 12397.7 12461.1 12461.8 12592.1 23098.9 23143.0 23202.8 23244.4 23974.2 9155.8 9156.4 9184.0 9205.5 9249.9 4987.9 5137.3 5138.5 5171.6 5318.2 3730.7 3861.5 3877.7 3879.8 3890.1 651.6 654.5 657.8 658.9 672.0 698.8 722.8 759.8 770.3 793.5 9486.9 9780.8 9844.7 9929.9 10346.0 720.2 728.6 728.7 730.9 790.4 4436.7 4546.7 4599.4 4636.4 4640.8 102528.5 129254.0 130035.0 130195.0 133339.0 73734.0 98326.5 98398.5 99076.0 99357.5 53929.5 55288.2 86735.0 88578.0 90013.0 10367.8 10464.8 10503.1 10571.8 10677.6 37727.4 49810.3 52274.2 53273.7 55696.0 74042.0 74157.3 74585.6 77601.2 78893.2 14064.3 14085.8 14087.3 14553.8 14917.4 16344.3 16866.6 16936.9 16944.4 17057.2 15337.4 15399.8 15912.9 16053.0 16632.9 14043.0 14282.8 14284.4 15030.8 15700.4 14109.6 14995.9 15117.5 15578.4 16584.7 12012.2 12547.6 12776.4 12809.2 13010.6 11969.6 12331.6 12386.2 12598.0 12944.9 07:19:20 INFO - PROCESS | 18319 | -------- Summary: end -------- 07:19:20 INFO - PROCESS | 18319 | 07:19:21 INFO - TEST-INFO | 18319: exit 0 07:19:21 INFO - TEST-OK | dromaeo_css | took 1114259ms 07:19:21 INFO - TEST-START | kraken 07:19:21 INFO - operating with platform_type : linux_ 07:19:21 INFO - Initialising browser for kraken test... 07:19:21 INFO - TEST-INFO | started process 18526 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSHdopY/profile http://localhost:58561/getInfo.html) 07:19:25 INFO - PROCESS | 18526 | __metrics Screen width/height:1600/1200 07:19:25 INFO - PROCESS | 18526 | colorDepth:24 07:19:25 INFO - PROCESS | 18526 | Browser inner width/height: 1024/697 07:19:25 INFO - PROCESS | 18526 | __metrics 07:19:26 INFO - PROCESS | 18526 | 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:26 INFO - PROCESS | 18526 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:19:27 INFO - TEST-INFO | 18526: exit 0 07:19:27 INFO - Browser initialized. 07:19:27 INFO - Running cycle 1/1 for kraken test... 07:19:27 INFO - TEST-INFO | started process 18616 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSHdopY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:19:39 INFO - PROCESS | 18616 | RSS: Main: 145518592 07:19:39 INFO - PROCESS | 18616 | 07:19:42 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58561/tests/kraken/driver.html?testName=audio-beat-detection) 07:19:42 INFO - PROCESS | 18616 | RSS: Main: 191938560 07:19:42 INFO - PROCESS | 18616 | 07:19:45 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58561/tests/kraken/driver.html?testName=audio-dft) 07:19:46 INFO - PROCESS | 18616 | RSS: Main: 181243904 07:19:46 INFO - PROCESS | 18616 | 07:19:48 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58561/tests/kraken/driver.html?testName=audio-fft) 07:19:49 INFO - PROCESS | 18616 | RSS: Main: 271458304 07:19:49 INFO - PROCESS | 18616 | 07:19:51 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58561/tests/kraken/driver.html?testName=audio-oscillator) 07:19:52 INFO - PROCESS | 18616 | RSS: Main: 175480832 07:19:52 INFO - PROCESS | 18616 | 07:19:54 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58561/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:19:54 INFO - PROCESS | 18616 | RSS: Main: 177602560 07:19:54 INFO - PROCESS | 18616 | 07:19:57 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58561/tests/kraken/driver.html?testName=imaging-darkroom) 07:19:58 INFO - PROCESS | 18616 | RSS: Main: 213610496 07:19:58 INFO - PROCESS | 18616 | 07:20:01 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58561/tests/kraken/driver.html?testName=imaging-desaturate) 07:20:02 INFO - PROCESS | 18616 | RSS: Main: 211554304 07:20:02 INFO - PROCESS | 18616 | 07:20:05 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58561/tests/kraken/driver.html?testName=json-parse-financial) 07:20:05 INFO - PROCESS | 18616 | RSS: Main: 211611648 07:20:05 INFO - PROCESS | 18616 | 07:20:07 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58561/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:20:07 INFO - PROCESS | 18616 | RSS: Main: 176156672 07:20:07 INFO - PROCESS | 18616 | 07:20:09 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58561/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:20:10 INFO - PROCESS | 18616 | RSS: Main: 183128064 07:20:10 INFO - PROCESS | 18616 | 07:20:12 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58561/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:20:12 INFO - PROCESS | 18616 | RSS: Main: 185843712 07:20:12 INFO - PROCESS | 18616 | 07:20:15 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58561/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:20:15 INFO - PROCESS | 18616 | RSS: Main: 181370880 07:20:15 INFO - PROCESS | 18616 | 07:20:18 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58561/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:20:19 INFO - PROCESS | 18616 | RSS: Main: 184623104 07:20:19 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | Cycle 1(1): loaded http://localhost:58561/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58561/tests/kraken/driver.html?testName=ai-astar) 07:20:20 INFO - PROCESS | 18616 | RSS: Main: 235450368 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | __start_tp_report 07:20:20 INFO - PROCESS | 18616 | _x_x_mozilla_page_load 07:20:20 INFO - PROCESS | 18616 | _x_x_mozilla_page_load_details 07:20:20 INFO - PROCESS | 18616 | |i|pagename|runs| 07:20:20 INFO - PROCESS | 18616 | |0;ai-astar;103;110;109;110;110;109;109;110;110;109 07:20:20 INFO - PROCESS | 18616 | |1;audio-beat-detection;145;116;119;187;116;117;144;115;145;116 07:20:20 INFO - PROCESS | 18616 | |2;audio-dft;160;162;162;160;161;161;162;161;161;159 07:20:20 INFO - PROCESS | 18616 | |3;audio-fft;75;76;75;115;74;76;75;75;102;74 07:20:20 INFO - PROCESS | 18616 | |4;audio-oscillator;84;88;139;83;84;84;122;85;83;128 07:20:20 INFO - PROCESS | 18616 | |5;imaging-gaussian-blur;116;117;116;116;116;117;117;117;116;117 07:20:20 INFO - PROCESS | 18616 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;146;146 07:20:20 INFO - PROCESS | 18616 | |7;imaging-desaturate;95;95;95;95;95;95;95;94;95;95 07:20:20 INFO - PROCESS | 18616 | |8;json-parse-financial;51;49;49;50;50;51;51;50;50;50 07:20:20 INFO - PROCESS | 18616 | |9;json-stringify-tinderbox;60;63;60;59;60;59;59;60;60;60 07:20:20 INFO - PROCESS | 18616 | |10;stanford-crypto-aes;72;72;72;73;77;72;82;74;71;73 07:20:20 INFO - PROCESS | 18616 | |11;stanford-crypto-ccm;118;123;119;123;118;125;122;121;156;115 07:20:20 INFO - PROCESS | 18616 | |12;stanford-crypto-pbkdf2;153;160;154;153;152;155;150;150;151;155 07:20:20 INFO - PROCESS | 18616 | |13;stanford-crypto-sha256-iterative;61;61;62;61;61;61;60;61;61;61 07:20:20 INFO - PROCESS | 18616 | __end_tp_report 07:20:20 INFO - PROCESS | 18616 | __start_cc_report 07:20:20 INFO - PROCESS | 18616 | _x_x_mozilla_cycle_collect,1640 07:20:20 INFO - PROCESS | 18616 | __end_cc_report 07:20:20 INFO - PROCESS | 18616 | __startTimestamp1472739620925__endTimestamp 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | ------- Summary: start ------- 07:20:20 INFO - PROCESS | 18616 | Number of tests: 14 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#0] ai-astar Cycles:10 Average:108.90 Median:110.00 stddev:2.13 (1.9%) stddev-sans-first:0.53 07:20:20 INFO - PROCESS | 18616 | Values: 103.0 110.0 109.0 110.0 110.0 109.0 109.0 110.0 110.0 109.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#1] audio-beat-detection Cycles:10 Average:132.00 Median:131.50 stddev:23.47 (17.8%) stddev-sans-first:24.42 07:20:20 INFO - PROCESS | 18616 | Values: 145.0 116.0 119.0 187.0 116.0 117.0 144.0 115.0 145.0 116.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.00 07:20:20 INFO - PROCESS | 18616 | Values: 160.0 162.0 162.0 160.0 161.0 161.0 162.0 161.0 161.0 159.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#3] audio-fft Cycles:10 Average:81.70 Median:75.00 stddev:14.47 (19.3%) stddev-sans-first:15.14 07:20:20 INFO - PROCESS | 18616 | Values: 75.0 76.0 75.0 115.0 74.0 76.0 75.0 75.0 102.0 74.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:84.00 stddev:22.27 (26.5%) stddev-sans-first:23.04 07:20:20 INFO - PROCESS | 18616 | Values: 84.0 88.0 139.0 83.0 84.0 84.0 122.0 85.0 83.0 128.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 07:20:20 INFO - PROCESS | 18616 | Values: 116.0 117.0 116.0 116.0 116.0 117.0 117.0 117.0 116.0 117.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 07:20:20 INFO - PROCESS | 18616 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 07:20:20 INFO - PROCESS | 18616 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.74 (1.5%) stddev-sans-first:0.71 07:20:20 INFO - PROCESS | 18616 | Values: 51.0 49.0 49.0 50.0 50.0 51.0 51.0 50.0 50.0 50.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:1.15 (1.9%) stddev-sans-first:1.22 07:20:20 INFO - PROCESS | 18616 | Values: 60.0 63.0 60.0 59.0 60.0 59.0 59.0 60.0 60.0 60.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#10] stanford-crypto-aes Cycles:10 Average:73.80 Median:73.00 stddev:3.33 (4.6%) stddev-sans-first:3.46 07:20:20 INFO - PROCESS | 18616 | Values: 72.0 72.0 72.0 73.0 77.0 72.0 82.0 74.0 71.0 73.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:122.50 stddev:11.63 (9.5%) stddev-sans-first:12.13 07:20:20 INFO - PROCESS | 18616 | Values: 118.0 123.0 119.0 123.0 118.0 125.0 122.0 121.0 156.0 115.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.30 Median:153.50 stddev:2.98 (1.9%) stddev-sans-first:3.16 07:20:20 INFO - PROCESS | 18616 | Values: 153.0 160.0 154.0 153.0 152.0 155.0 150.0 150.0 151.0 155.0 07:20:20 INFO - PROCESS | 18616 | 07:20:20 INFO - PROCESS | 18616 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.00 Median:61.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 07:20:20 INFO - PROCESS | 18616 | Values: 61.0 61.0 62.0 61.0 61.0 61.0 60.0 61.0 61.0 61.0 07:20:20 INFO - PROCESS | 18616 | -------- Summary: end -------- 07:20:20 INFO - PROCESS | 18616 | 07:20:21 INFO - TEST-INFO | 18616: exit 0 07:20:21 INFO - TEST-OK | kraken | took 60515ms 07:20:21 INFO - SUITE-END | took 1174s 07:20:22 INFO - Completed test suite (00:19:34) 07:20:22 INFO - javascript benchmark 07:20:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3204.7436205103595, 3243.691849274318, 3254.8535315910776, 3262.918427039322, 3297.7361811055102, 5641.012822756016, 5758.539316854647, 5765.7981970631035, 5772.076467765159, 5782.335031471382, 5722.284714410699, 5782.653040817554, 5814.941850581493, 5815.62198457101, 5825.126231065342, 5078.31442755227, 5187.688738675692, 5267.078261304266, 5268.236105764662, 5321.973390133044, 5254.474552544734, 5298.894022119555, 5304.283921670583, 5305.6551324164, 5308.283381743454, 5169.302144210521, 5185.637005409623, 5203.0374380739495, 5203.895922081575, 5289.920651190216, 5303.787848486075, 5306.416294207673, 5313.0, 5315.388730296027, 5347.0909945309395, 5081.441041485434, 5149.794008239666, 5179.896402071938, 5208.557272631793, 5208.5572726318305, 5575.135853942646, 5606.299212598426, 5614.214010038598, 5624.803131890364, 5649.887002259932, 6097.80419217806, 6102.8779424411705, 6109.602875813058, 6112.651429998751, 6117.62829776842, 6050.45545900871, 6128.264608247039, 6143.262808463013, 6164.568480206387, 6188.504919606421, 5775.1337299405, 5777.335606405275, 5786.739596718157, 5787.392323806024, 5788.594798364116, 6326.557141000086, 6337.366263373718, 6341.143945567289, 6342.23893132827, 6345.714442850082, 5880.852978675567, 5966.582339236299, 6022.909656355158, 6030.638161710223, 6085.073549543254, 6203.31763506014, 6206.782762603287, 6210.658413787195, 6213.316535181127, 6220.377962203744, 6097.8475538111015, 6144.56988010835, 6160.784372546939, 6165.722542485599, 6168.9691551541955, 5957.314908785545, 6009.669468179205, 6045.455908968126, 6095.0, 6167.938320616826, 4657.324687920301, 4693.413323334583, 4697.600703940203, 4723.504032076651, 4746.667733258639, 4163.958360416417, 4178.247915375262, 4178.791060446965, 4190.455240818674, 4203.306454435045, 5669.262995810518, 5709.25779648643, 5735.283089613798, 5755.395683453177, 5778.046622307356, 6166.9383306167265, 6174.938250617526, 6177.96911015442, 6180.196574445294, 6183.227096612924, 5805.390434004453, 5808.564357673192, 5831.241938548046, 5837.620554663933, 5850.297964244318, 5166.61250406221, 5267.604929630293, 5272.92090618641, 5322.707251101227, 5332.866678333073, 6375.872482550323, 6436.356364363526, 6457.031445283151, 6460.321666224979, 6463.7091330890225, 6234.906476402767, 6253.249610046823, 6255.437010669061, 6260.154879091265, 6263.311035785969, 6210.472109870531, 6281.685915704105, 6315.526335524946, 6351.047342898602, 6461.224653041665, 6070.939290607214, 6079.544034197541, 6083.6045657032155, 6088.3911608840535, 6121.785737499166, 6194.504439644727, 6207.193064901715, 6214.937850621617, 6225.501959843292, 6231.594946328474, 5882.588218824641, 5966.194563734014, 6002.6398416093425, 6006.459418652343, 6077.756889724362, 5990.550708696778, 6029.728662210124, 6030.969845150747, 6035.849103772441, 6055.212822333068, 5980.19267398913, 6003.849903752266, 6023.849403764941, 6044.032954727399, 6046.637201767908, 5912.526997840248, 5943.821685349447, 5998.25021872266, 6006.039033754579, 6098.634081955097, 5887.381824908494, 5892.9116063258225, 5896.852578685567, 5900.940990590039, 5920.585559011], "value": 5666.909954525674, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.8413070536305, 621.434494609905, 667.4760313154175, 671.6809515480151, 685.1777866560129, 34158.0, 37336.439953400724, 37380.0, 37433.06417339564, 37433.625663743354, 39199.21601567967, 39513.802430987875, 40057.59942400575, 47593.524064759345, 49312.0, 9095.545222738852, 9202.079792020812, 9215.907840921573, 9216.861747073799, 9218.723438296829, 9095.0, 9181.632734690638, 9191.95404022979, 9192.954035229814, 9195.999999999967, 8662.653493860238, 9178.082191780803, 11066.0, 11376.943115284412, 11388.088952883749, 11211.943940280327, 11264.324140551593, 11278.830817537744, 11288.209825312273, 11288.322700637988, 11061.944690276578, 11088.611898583511, 11120.165987550967, 11120.61077862279, 11145.665630031031, 35115.473267901005, 44269.0, 45423.77288113538, 45838.0, 48790.512094878955, 3994.3409337459284, 4106.527749308838, 4176.436181115571, 4282.743035417885, 4534.773261336953, 34223.31553368919, 34298.31403371919, 34367.484487732705, 34507.0, 34829.0, 4069.3285607874664, 4096.631303182728, 4112.527059388179, 4117.197146556423, 4216.2832318505925, 374990.0, 375545.0, 375725.0, 375978.0, 376281.11859440804, 58563.414365856646, 63504.0, 65037.67481162659, 65258.67370663116, 65608.34391656118, 62448.37551624516, 67771.32228677749, 67800.6609966947, 67878.32121678819, 67956.6602166986, 19313.613727725366, 19589.216431342586, 19593.510162245773, 19601.1179496923, 19611.117499712545, 14758.335874885655, 14937.850621493862, 14942.327595258239, 14944.551663450115, 14962.551123466314, 17062.658746824993, 18974.62050758977, 18997.47637191356, 19105.90447047756, 19346.613067738846, 5588.590959514693, 6489.383508566679, 6584.9012264816065, 6703.564268322543, 6716.630585317855, 982.9115379615725, 1050.5325130317087, 1120.708615759892, 1227.110344999868, 1239.9504019839285, 35123.473147902805, 35206.0, 35660.46509302311, 35802.64197358045, 35927.4610880837, 1139.0261326565833, 1143.228320883417, 1151.4357964597286, 1151.5796734192127, 1177.5996161305197, 18308.267669293346, 18614.906925465555, 18646.0, 18688.439346819618, 18714.438566843015, 515594.0, 516643.0, 517793.0, 518928.0, 521277.0, 57411.425885741446, 61638.0, 63146.68426657837, 64218.35781642124, 64407.0, 1461.4446510326009, 1496.7231062253518, 1498.9850101499283, 1500.2948614151549, 1501.204361688307, 34919.65080349164, 36101.277974439836, 36385.61438561438, 36680.44979325259, 36789.0, 56878.43121568731, 57602.0, 57667.423325767886, 57674.71162644328, 57702.7114864423, 515576.0, 528497.0, 529300.0, 776367.0, 777665.0, 29967.550486743152, 30111.397772043998, 30296.84851575728, 30326.545101823933, 30620.387592248477, 51777.0, 52052.739736301075, 52402.0, 52702.20946685726, 53502.46497535131, 42651.7867410661, 43950.78024609984, 44527.332090018026, 44663.77668111639, 45329.0, 43717.56282437135, 44488.55511444844, 44922.10155796931, 45356.09287814159, 45995.77002114968, 43984.34023489586, 44608.10783784371, 45112.54887451215, 45312.54687453083, 45372.0, 36135.63864361323, 39128.413073804484, 39370.212595747354, 39478.802605986784, 39814.2037159261, 30140.0, 30266.0, 30282.394352113275, 30285.394292114474, 30645.846770766002], "value": 21776.525373552686, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.51304651801547, 400.7635495057919, 410.70018886213063, 414.5779974686825, 421.928272193727, 14739.48411805587, 20317.0, 20851.0, 20893.47766305843, 21451.141954321807, 20520.589588208266, 20548.280810171647, 20558.280460183858, 20587.794122058775, 20846.0, 5644.491995720385, 5680.9715951420185, 5809.331926828406, 5841.591088623798, 5845.85385365365, 5694.259746232984, 5740.282464691913, 5740.827775166752, 5761.625494342854, 5762.7406766695385, 5677.261955945722, 5792.275965504312, 5827.388124246957, 5831.737571809257, 5832.737526811304, 5607.719614019283, 5781.075027995522, 5807.796727114531, 5808.04167312395, 5826.009578371666, 5183.663061900965, 5217.3999990000875, 5219.451957544479, 5220.556252718486, 5223.660462069954, 24112.75887241123, 24285.878570607212, 24330.14844480435, 24545.754542454524, 24578.877105614538, 2389.438481956739, 2438.5366780311683, 2475.9009639614574, 2789.205076553193, 2899.5795609636693, 11206.439678016148, 11394.772104557862, 14513.984021118526, 15477.148756818371, 16226.756598651029, 2095.8847263400507, 2102.2747152232455, 2120.300300900699, 2144.281665642012, 2207.4702071502884, 84984.0, 86172.0, 87755.56122219411, 88479.11520884772, 90467.0, 9759.65841195569, 10214.591416343412, 10314.48427578618, 10407.47962601882, 11527.538898444136, 13876.653083672989, 13889.79165312521, 13892.16647001183, 13929.373178207004, 13937.442502299795, 8310.382342275168, 8373.330133589297, 8494.957525212334, 8517.701880434155, 8716.956415217883, 8337.207965243293, 8375.157465357612, 8445.619947102272, 8518.70184543538, 8538.103499132625, 4351.129774045204, 4370.344448332776, 4389.078293558388, 4390.0, 4416.0, 1985.146387053552, 2020.2525065725777, 2026.8885211313225, 2053.9486512837, 2062.1339037604243, 986.3982970388057, 993.4734590666958, 1018.2871989607305, 1058.1059005140644, 1074.8925107489188, 20330.491737706674, 20523.0, 21241.575168496543, 21401.46496337573, 21702.674459883114, 978.8678528398575, 1014.4927536231884, 1042.0829669890447, 1094.3269889018356, 1159.0611604600301, 11270.267432616854, 11642.185047046794, 11734.882651173379, 11913.761724765629, 11920.761584768255, 437834.0, 449693.0, 519165.0, 526731.0, 539707.0, 92832.53583732038, 94439.0, 95029.0, 95623.0, 97716.51141744245, 1035.7876635289747, 1065.9893401066045, 1072.3137192197, 1083.8482612434266, 1086.1256688365784, 18257.817421826145, 18506.44480665582, 18782.15480303417, 18917.90541047341, 18922.0, 46515.0, 46542.30186547272, 46707.0, 46988.53011469841, 47817.52182478131, 62337.376626233156, 63093.68453157705, 63692.68153659202, 64115.35884641094, 65645.67177164083, 8801.07588703177, 8809.42738721981, 9014.23379012765, 9117.452952822852, 9218.354715170142, 12484.313362765135, 13192.076554641082, 13229.47082116743, 13554.18674879471, 13657.38541765603, 12443.191192572454, 12477.126601137834, 12765.93617031909, 12779.23324600527, 12783.552575659807, 12195.390230488618, 12222.327771972627, 12389.070819688739, 12413.565525206574, 12839.165454245443, 11951.521939122338, 11956.163068585116, 12337.691557711129, 12406.56577019836, 12544.372781360713, 8814.779630509289, 8989.865152022594, 9015.459072455673, 9103.954480227556, 9283.210927071275, 9189.678361257591, 11616.303021818718, 12108.818367724667, 12125.333106679218, 12125.514979400725], "value": 9629.17623793867, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.5119259451785, 1584.7622856571516, 1626.2031604513788, 1826.3151318255655, 1929.7298378227051, 9188.908110918888, 9477.052294770529, 9577.185939195168, 9690.0, 9700.175485083759, 9760.804783904317, 9894.15899648529, 9895.455749933752, 9905.0, 9906.108450239477, 2542.7584379483965, 2564.3973666188526, 2568.0883286433327, 2568.4863027394504, 2569.9486010279784, 2523.0, 2565.384307766132, 2565.8460492370423, 2566.461043180925, 2570.0747730816984, 2486.191987604027, 2492.663490428787, 2493.4265119022543, 2499.7750202481775, 2534.4424226670103, 2412.8190385721055, 2417.093589903786, 2428.1258746851117, 2431.100492817669, 2453.6564880916685, 2403.903843846244, 2408.277516744987, 2444.278937713379, 2448.1431498975394, 2451.3994071452607, 9549.904500954972, 9690.079442452956, 9811.0, 9811.509424528746, 9832.705018849374, 5582.413846546136, 5753.424657534255, 5758.88482230353, 5770.971145144289, 5803.187553742479, 3489.2672538766888, 3507.982460087683, 3550.786952782841, 3589.46158076288, 3594.6764791168657, 1128.9379084150369, 1133.471205854277, 1147.9655610331704, 1156.121347775688, 1163.7497937943383, 13289.534866279633, 13292.73414531704, 13344.332783360793, 13350.933245333808, 13391.129576577492, 4883.682560633548, 4894.045661096124, 4976.527229913152, 5056.26684130799, 5105.846824595268, 10949.288296260718, 10959.83560246597, 10975.725606859733, 10979.0, 10982.780344393068, 5774.509166720875, 5775.191473193756, 5778.508826749773, 5786.797462088806, 5798.9130163047585, 4611.861644150681, 4696.204300860555, 4779.474257831635, 5115.0, 5392.40683524812, 4061.6953728470485, 4098.959010409858, 4157.8336866525, 4162.771047592351, 4195.979020104941, 1234.1977714485656, 1267.352441825623, 1280.9359532023361, 1287.0990306785288, 1295.1257900916844, 1086.375334182848, 1096.1723898456614, 1106.0819519798547, 1108.9611863584735, 1119.5018216893404, 6293.559450838397, 6500.6424646643945, 6720.462363010947, 6843.863122737517, 6915.377616014583, 495.45499950054653, 501.93725784276967, 504.12031005894215, 509.6508891409396, 521.0698902459093, 4487.259602165606, 4660.743659098782, 4665.46347170073, 4673.579377856009, 4729.527047295243, 11642.0, 11854.258296681397, 12132.45403956824, 12175.512979480898, 12199.451024703909, 11899.702507437383, 12101.394930253453, 12105.152639315163, 12175.452104655134, 12177.93911030457, 9180.10826589622, 9223.44659320443, 9338.252939764803, 9374.67188648394, 9435.528223588793, 6672.165979252593, 6785.117934668461, 6846.726130954905, 6848.0, 6865.485088618274, 10919.727006824893, 11316.434178291218, 11410.087193024372, 11410.201285910238, 11484.138689598147, 11094.278871873303, 11133.88866111361, 11324.830127548259, 11349.319040857576, 11494.195406321478, 7825.647845847065, 7835.686572537034, 7852.097008844056, 7860.174681658572, 7935.444518883623, 9189.678361257324, 9273.814523709623, 9274.675386361445, 9277.443353398818, 9282.118198771106, 7941.205879412013, 8294.875576866472, 8372.665093396196, 8554.91445085541, 8720.78198045054, 8242.332540290814, 8246.793830154294, 8310.70912518059, 8345.95827020861, 8414.495130291958, 7106.751263705745, 7304.233055529306, 7374.0, 7754.728584499516, 7809.609519523888, 5615.438456154352, 5830.2129212555765, 5864.384239654945, 5865.94134058654, 6058.606190597597, 5657.717114144194, 5757.7121143941795, 5769.1634712967225, 5784.2769653793275, 5945.970270148795], "value": 4946.220100514317, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.59979524059224, 350.14040530497624, 356.3549974546073, 356.9214772749996, 362.38575614333706, 5459.0, 5502.80740174094, 5509.917351239735, 5549.528290095342, 5557.527610153132, 5437.592180586452, 5446.747248132931, 5449.618526703132, 5451.918221226675, 5480.301106656939, 4054.869383542181, 4319.913601727963, 4340.348947657845, 4387.5173730889655, 4397.824087036531, 3973.46358241638, 4280.208161490119, 4280.6361459275995, 4308.82764689412, 4324.345927691096, 4270.957290427103, 4279.587736047112, 4281.079567892903, 4283.37891005804, 4304.268274393345, 4099.487564054493, 4266.997255644921, 4291.399204111427, 4321.783910804447, 4326.918270432408, 3633.6911362534215, 3786.727963222072, 3793.6016718244464, 3803.581606023335, 3823.388257878726, 5306.628536002481, 5315.335583052119, 5319.5478384337375, 5328.600354973354, 5348.973255133699, 4645.442546894395, 4893.290472881412, 4900.534449227318, 4930.211166213388, 4946.233333833262, 5687.435955112336, 5690.2033715279895, 5694.9430505695245, 5715.2855893013375, 5717.942820571782, 4151.771652559108, 4155.771432571208, 4158.958410415888, 4174.436451079096, 4178.0, 5785.421457854223, 5894.0, 5947.51312171957, 6022.638641681513, 6036.456718895321, 4261.659067274611, 4264.594863487963, 4273.145370925828, 4282.9785851071165, 4284.764337961442, 4291.957080429218, 4300.935485967713, 4312.935305970413, 4321.416608757841, 4333.631641310524, 4645.86062418128, 4888.5111488850835, 4917.975410122998, 4918.1884988977135, 4954.677945933503, 5717.199592057115, 5748.016934157709, 5764.279465066867, 5768.0, 5793.0, 1473.1823837770141, 1492.3434987164455, 1495.6410461489381, 1503.9096654925092, 1505.0969418348902, 904.3244498692862, 941.5715849288557, 942.4392486470676, 943.7263193673758, 960.6493629825063, 4901.926471102936, 4939.234418665041, 4958.380202474691, 5288.720992984374, 5441.340391180649, 5880.970595147082, 5897.7935772247765, 5908.586398952118, 5928.525717942554, 5953.732082056318, 5591.909577632323, 5612.579056570774, 5641.097424412074, 5651.4348565143155, 5654.604177707603, 3049.405365953662, 3131.42068717288, 3152.511360739089, 3153.44814657434, 3167.5270998985584, 6107.389261073857, 6128.693565321716, 6130.049842274455, 6134.969325153345, 6150.661713605795, 5722.37053924068, 5929.466348028698, 5981.401859813984, 6003.459688627957, 6065.423784740443, 6056.485198758067, 6057.969710151421, 6095.786647467317, 6096.3903609639565, 6129.080637904171, 5748.597598168255, 5888.941110588839, 5897.203877476486, 5954.37479064692, 5996.910046349221, 5836.503897168788, 5845.4801751544055, 5867.237259156283, 5871.706414679335, 5889.617174883619, 5982.760689572368, 6111.297200821962, 6124.448799608057, 6125.754969801158, 6129.570930034854, 3987.720859539804, 4149.813258403318, 4157.355609880473, 4166.729162604421, 4184.91630167401, 5825.155352473952, 5864.9706751467675, 5880.088586269135, 5886.676232807236, 5890.263717035371, 5707.086866101404, 5744.425557444222, 5755.482006619424, 5763.9135412968, 5766.798162064308, 5570.721463926869, 5753.884922301614, 5758.8848223034465, 5759.9136012959, 5813.680247586254, 5718.685472298897, 5758.424157584208, 5761.625494342854, 5761.654300741969, 5765.4522820332, 5395.271638328933, 5675.68783716883, 5792.652440853562, 5834.883302334015, 5847.8245652630485, 5552.916706249491, 5599.628091117702, 5688.260526131577, 5688.971555142198, 5691.971540142273], "value": 4385.584758677295, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [814.5031530766231, 819.2544784246334, 827.0365024746171, 853.8676505141701, 859.5959898847543, 15779.0, 16392.672146557063, 16533.834661653378, 16583.66832663346, 16618.0, 16144.83855161448, 16444.588885277873, 16479.093649849252, 16498.25757840897, 16500.83499165008, 732.0702707561394, 848.1476116502906, 863.6804382378529, 865.0959747064302, 869.9434536755107, 871.3464901324007, 872.2062922740307, 872.8472517309477, 872.8472517309477, 873.0483772687769, 928.6007016982695, 928.8281667891428, 928.9256859451227, 929.0802105915129, 929.419113054341, 767.8272388712551, 895.3642913531401, 920.8987011428737, 937.8265020971143, 941.6751220828803, 698.7274962764526, 698.800841760101, 698.8567343694581, 699.098163369253, 699.248308068828, 15799.368025278975, 15810.0, 15819.920900395424, 15857.286422111032, 15894.602634934103, 14838.25808709571, 15174.24128793556, 15244.161571113584, 15321.540353789293, 15570.610734731608, 13832.654183645389, 13994.510192143227, 14229.359678814479, 14367.353469093916, 14402.279886005657, 4548.636109111296, 4563.612092972101, 4636.3045543168455, 4684.414448193976, 4710.811567537295, 88159.11840881573, 88885.55557222173, 89703.55148224217, 89731.55134224288, 89757.55121224352, 11843.703907402383, 12397.690057748629, 12461.12772105962, 12461.813072803916, 12592.118551701476, 23098.884505577364, 23143.0, 23202.76797232006, 23244.41888952756, 23974.160904368262, 9155.81688366229, 9156.404833685789, 9184.0, 9205.539723013957, 9249.907500924905, 4987.925181122286, 5137.306463627438, 5138.486151384832, 5171.5862730981435, 5318.175682769177, 3730.720195985312, 3861.5366156061455, 3877.747946383476, 3879.786611736325, 3890.1247219375414, 651.5960104735094, 654.5189285874878, 657.7533424965637, 658.9176352955881, 671.9529632925746, 698.7763915546976, 722.8482018776011, 759.7682706774376, 770.2836362183111, 793.4564823096198, 9486.857697134548, 9780.804383912282, 9844.704658860246, 9929.851052234222, 10346.0, 720.1753991679498, 728.6101935464501, 728.65024788102, 730.8940203670438, 790.4190420041265, 4436.689431739747, 4546.749928753884, 4599.379083823708, 4636.420447444069, 4640.83757068501, 102528.48735756273, 129254.0, 130035.0, 130195.0, 133339.0, 73734.0, 98326.5083674577, 98398.50800746094, 99076.0, 99357.50321248347, 53929.46070539323, 55288.17067743987, 86735.0, 88578.0, 90013.0, 10367.844482332923, 10464.790704186025, 10503.05472507478, 10571.788564228826, 10677.626283080055, 37727.434088488146, 49810.25284620661, 52274.21588676097, 53273.7336313316, 55696.0, 74042.0, 74157.2584274172, 74585.62707186429, 77601.2239877594, 78893.21106788858, 14064.296785160906, 14085.788713169515, 14087.295635218403, 14553.781693274821, 14917.403303867724, 16344.264508097402, 16866.578335541708, 16936.915315423343, 16944.40694575684, 17057.232424541173, 15337.386504539694, 15399.846001540289, 15912.92043539775, 16053.0, 16632.916835415745, 14043.0, 14282.785758213842, 14284.42862285497, 15030.849691503383, 15700.371985120468, 14109.576712698636, 14995.850041499882, 15117.54647360581, 15578.376864925276, 16584.66830663404, 12012.219205751599, 12547.62357129251, 12776.425060872098, 12809.167404118702, 13010.609681709564, 11969.64091077269, 12331.568395106127, 12386.194897331645, 12598.0, 12944.870551294744], "value": 8118.258022546889, "unit": "score"}], "name": "dromaeo_css", "value": 7705.283249128892, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 110.0, 109.0, 110.0, 110.0, 109.0, 109.0, 110.0, 110.0, 109.0], "value": 108.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 119.0, 187.0, 116.0, 117.0, 144.0, 115.0, 145.0, 116.0], "value": 132.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 162.0, 160.0, 161.0, 161.0, 162.0, 161.0, 161.0, 159.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 115.0, 74.0, 76.0, 75.0, 75.0, 102.0, 74.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 139.0, 83.0, 84.0, 84.0, 122.0, 85.0, 83.0, 128.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 50.0, 50.0, 51.0, 51.0, 50.0, 50.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 60.0, 59.0, 60.0, 59.0, 59.0, 60.0, 60.0, 60.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 73.0, 77.0, 72.0, 82.0, 74.0, 71.0, 73.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 123.0, 119.0, 123.0, 118.0, 125.0, 122.0, 121.0, 156.0, 115.0], "value": 124.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 160.0, 154.0, 153.0, 152.0, 155.0, 150.0, 150.0, 151.0, 155.0], "value": 153.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 61.0, 61.0, 61.0, 60.0, 61.0, 61.0, 61.0], "value": 61.0, "unit": "score"}], "name": "kraken", "value": 1461.1, "alertThreshold": 2.0}]} 07:20:22 INFO - Return code: 0 07:20:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:20:22 INFO - # TBPL SUCCESS # 07:20:22 INFO - Running post-action listener: _resource_record_post_action 07:20:22 INFO - [mozharness: 2016-09-01 14:20:22.224964Z] Finished run-tests step (success) 07:20:22 INFO - Running post-run listener: _resource_record_post_run 07:20:22 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 326934528; Read time: 8; Write time: 388128 07:20:22 INFO - TinderboxPrint: CPU usage
13.0% 07:20:22 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 07:20:22 INFO - TinderboxPrint: I/O write bytes / time
326,934,528 / 388,128 07:20:22 INFO - TinderboxPrint: CPU idle
8,279.9 (87.0%) 07:20:22 INFO - TinderboxPrint: CPU user
1,184.0 (12.4%) 07:20:22 INFO - TinderboxPrint: Swap in / out
0 / 0 07:20:22 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78708736; Read time: 0; Write time: 138848 07:20:22 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 247267328; Read time: 0; Write time: 249276 07:20:22 INFO - Running post-run listener: _upload_blobber_files 07:20:22 INFO - Blob upload gear active. 07:20:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:20:22 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:22 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:22 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:23 INFO - (blobuploader) - INFO - Open directory for files ... 07:20:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:20:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:20:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:24 INFO - (blobuploader) - INFO - Done attempting. 07:20:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:20:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:24 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 - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:20:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:20:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:20:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:20:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:20:30 INFO - (blobuploader) - INFO - Done attempting. 07:20:30 INFO - (blobuploader) - INFO - Iteration through files over. 07:20:30 INFO - Return code: 0 07:20:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:20:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:20:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc98dd64da606451349ef9f5fa83c5fa514c51c71439163f1156a50fdde393c38fd49cee7d55c75ecfe7fde8d05cd3d17ff14113a38d212badd9de7910aae774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a4d3ad8747f653040041e632cc3b699b425641ee5c9b01c8b4df20f5874722f13862fdba858cb9ca5d0fea668e498e57ffa03e172502549904745390bcfb87e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b67b14bc0fac57e4362578788c444546d2961a5cc8e9aab59f0b14b27c414fb82119fde86c15c044d61651dea8068ff7d81f4f36ba07f3920b0ade373400c105"} 07:20:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:20:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:20:30 INFO - Contents: 07:20:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cc98dd64da606451349ef9f5fa83c5fa514c51c71439163f1156a50fdde393c38fd49cee7d55c75ecfe7fde8d05cd3d17ff14113a38d212badd9de7910aae774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a4d3ad8747f653040041e632cc3b699b425641ee5c9b01c8b4df20f5874722f13862fdba858cb9ca5d0fea668e498e57ffa03e172502549904745390bcfb87e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b67b14bc0fac57e4362578788c444546d2961a5cc8e9aab59f0b14b27c414fb82119fde86c15c044d61651dea8068ff7d81f4f36ba07f3920b0ade373400c105"} 07:20:30 INFO - Running post-run listener: copy_logs_to_upload_dir 07:20:30 INFO - Copying logs to upload dir... 07:20:30 INFO - mkdir: /builds/slave/test/build/upload/logs 07:20:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1681.845503 ========= master_lag: 3.47 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 5 secs) (at 2016-09-01 07:20:32.416479) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-01 07:20:32.421182) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848 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/cc98dd64da606451349ef9f5fa83c5fa514c51c71439163f1156a50fdde393c38fd49cee7d55c75ecfe7fde8d05cd3d17ff14113a38d212badd9de7910aae774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a4d3ad8747f653040041e632cc3b699b425641ee5c9b01c8b4df20f5874722f13862fdba858cb9ca5d0fea668e498e57ffa03e172502549904745390bcfb87e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b67b14bc0fac57e4362578788c444546d2961a5cc8e9aab59f0b14b27c414fb82119fde86c15c044d61651dea8068ff7d81f4f36ba07f3920b0ade373400c105"} 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.011389 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/cc98dd64da606451349ef9f5fa83c5fa514c51c71439163f1156a50fdde393c38fd49cee7d55c75ecfe7fde8d05cd3d17ff14113a38d212badd9de7910aae774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a4d3ad8747f653040041e632cc3b699b425641ee5c9b01c8b4df20f5874722f13862fdba858cb9ca5d0fea668e498e57ffa03e172502549904745390bcfb87e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b67b14bc0fac57e4362578788c444546d2961a5cc8e9aab59f0b14b27c414fb82119fde86c15c044d61651dea8068ff7d81f4f36ba07f3920b0ade373400c105"}' ========= master_lag: 1.11 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-09-01 07:20:33.547143) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:33.547524) ========= 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=a12cd81e0ef515edd9e0d800000001bb-1472734524.854933-119303848 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 2.45 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-09-01 07:20:35.999667) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:20:36.000007) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 07:20:36.311896) ========= ========= Total master_lag: 7.24 =========