builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1472826834.41 results: success (0) buildid: 20160902045242 builduid: c3f11ed22e584033828a7735f25bcd96 revision: b16b9d1eea136bba9d512610e79700192be4e646 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:54.411083) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:54.411713) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:54.412000) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:54.426993) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:54.427366) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-02 07:33:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.9M=0s 2016-09-02 07:33:55 (46.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.140069 ========= master_lag: 0.25 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:54.822417) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:54.822757) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019635 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:54.873183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:54.873628) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b16b9d1eea136bba9d512610e79700192be4e646 --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 b16b9d1eea136bba9d512610e79700192be4e646 --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=05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-02 07:33:55,656 truncating revision to first 12 chars 2016-09-02 07:33:55,656 Setting DEBUG logging. 2016-09-02 07:33:55,656 attempt 1/10 2016-09-02 07:33:55,656 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b16b9d1eea13?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-02 07:33:55,955 unpacking tar archive at: mozilla-aurora-b16b9d1eea13/testing/mozharness/ program finished with exit code 0 elapsedTime=0.944519 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:55.840810) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:55.841142) ========= script_repo_revision: b16b9d1eea136bba9d512610e79700192be4e646 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:55.841538) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:55.841896) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 07:33:55.850268) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-02 07:33:55.850566) ========= /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=05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:33:56 INFO - MultiFileLogger online at 20160902 07:33:56 in /builds/slave/test 07:33:56 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 07:33:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:33:56 INFO - {'append_to_log': False, 07:33:56 INFO - 'base_work_dir': '/builds/slave/test', 07:33:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:33:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:33:56 INFO - 'branch': 'Mozilla-Aurora', 07:33:56 INFO - 'buildbot_json_path': 'buildprops.json', 07:33:56 INFO - 'config_files': ('talos/linux_config.py',), 07:33:56 INFO - 'default_actions': ('clobber', 07:33:56 INFO - 'read-buildbot-config', 07:33:56 INFO - 'download-and-extract', 07:33:56 INFO - 'populate-webroot', 07:33:56 INFO - 'create-virtualenv', 07:33:56 INFO - 'install', 07:33:56 INFO - 'run-tests'), 07:33:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:33:56 INFO - 'download_minidump_stackwalk': True, 07:33:56 INFO - 'download_symbols': 'ondemand', 07:33:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:33:56 INFO - 'tooltool.py': '/tools/tooltool.py', 07:33:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:33:56 INFO - '/tools/misc-python/virtualenv.py')}, 07:33:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:33:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:33:56 INFO - 'installer_path': 'installer.exe', 07:33:56 INFO - 'log_level': 'info', 07:33:56 INFO - 'log_name': 'talos', 07:33:56 INFO - 'log_to_console': True, 07:33:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:33:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:33:56 INFO - 'opt_config_files': (), 07:33:56 INFO - 'pip_index': False, 07:33:56 INFO - 'sps_profile': False, 07:33:56 INFO - 'sps_profile_interval': 0, 07:33:56 INFO - 'suite': 'dromaeojs', 07:33:56 INFO - 'system_bits': '32', 07:33:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:33:56 INFO - 'title': 'talos-linux64-ix-069', 07:33:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:33:56 INFO - 'use_talos_json': True, 07:33:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:33:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:33:56 INFO - 'work_dir': 'build'} 07:33:56 INFO - [mozharness: 2016-09-02 14:33:56.714799Z] Running clobber step. 07:33:56 INFO - Running pre-action listener: _resource_record_pre_action 07:33:56 INFO - Running main action method: clobber 07:33:56 INFO - rmtree: /builds/slave/test/build 07:33:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:33:57 INFO - Running post-action listener: _resource_record_post_action 07:33:57 INFO - [mozharness: 2016-09-02 14:33:57.172022Z] Finished clobber step (success) 07:33:57 INFO - [mozharness: 2016-09-02 14:33:57.172121Z] Running read-buildbot-config step. 07:33:57 INFO - Running pre-action listener: _resource_record_pre_action 07:33:57 INFO - Running main action method: read_buildbot_config 07:33:57 INFO - Using buildbot properties: 07:33:57 INFO - { 07:33:57 INFO - "project": "", 07:33:57 INFO - "product": "firefox", 07:33:57 INFO - "script_repo_revision": "production", 07:33:57 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:33:57 INFO - "repository": "", 07:33:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:33:57 INFO - "buildid": "20160902045242", 07:33:57 INFO - "pgo_build": "True", 07:33:57 INFO - "basedir": "/builds/slave/test", 07:33:57 INFO - "buildnumber": 442, 07:33:57 INFO - "slavename": "talos-linux64-ix-069", 07:33:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:33:57 INFO - "platform": "ubuntu64_hw", 07:33:57 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:33:57 INFO - "branch": "mozilla-aurora", 07:33:57 INFO - "repo_path": "releases/mozilla-aurora", 07:33:57 INFO - "revision": "b16b9d1eea136bba9d512610e79700192be4e646", 07:33:57 INFO - "stage_platform": "linux64-pgo", 07:33:57 INFO - "builduid": "c3f11ed22e584033828a7735f25bcd96", 07:33:57 INFO - "slavebuilddir": "test" 07:33:57 INFO - } 07:33:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 07:33:57 INFO - Running post-action listener: _resource_record_post_action 07:33:57 INFO - [mozharness: 2016-09-02 14:33:57.174655Z] Finished read-buildbot-config step (success) 07:33:57 INFO - [mozharness: 2016-09-02 14:33:57.174743Z] Running download-and-extract step. 07:33:57 INFO - Running pre-action listener: _resource_record_pre_action 07:33:57 INFO - Running main action method: download_and_extract 07:33:57 INFO - mkdir: /builds/slave/test/build/tests 07:33:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:33:57 INFO - https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:33:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:33:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:33:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:33:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:33:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:33:57 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:33:57 INFO - retry: Failed, sleeping 30 seconds before retrying 07:34:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:34:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:34:27 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:34:27 INFO - retry: Failed, sleeping 60 seconds before retrying 07:35:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:35:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:35:27 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:35:27 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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! 07:35:27 INFO - Caught exception: HTTP Error 404: Not Found 07:35:27 INFO - Caught exception: HTTP Error 404: Not Found 07:35:27 INFO - Caught exception: HTTP Error 404: Not Found 07:35:27 INFO - trying https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:35:27 INFO - Downloading https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:35:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:35:27 INFO - Downloaded 1361 bytes. 07:35:27 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 07:35:27 INFO - Using the following test package requirements: 07:35:27 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:35:27 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:35:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:35:27 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:35:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:35:27 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:35:27 INFO - u'jsshell-linux-x86_64.zip'], 07:35:27 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:35:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:35:27 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 07:35:27 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:35:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:35:27 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:35:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:35:27 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:35:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:35:27 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 07:35:27 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:35:27 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:35:27 INFO - https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:35:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:35:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:35:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:35:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:35:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:35:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:35:27 INFO - retry: Failed, sleeping 30 seconds before retrying 07:35:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:35:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:35:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:35:57 INFO - retry: Failed, sleeping 60 seconds before retrying 07:36:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:36:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:36:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:36:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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! 07:36:57 INFO - Caught exception: HTTP Error 404: Not Found 07:36:57 INFO - Caught exception: HTTP Error 404: Not Found 07:36:57 INFO - Caught exception: HTTP Error 404: Not Found 07:36:57 INFO - trying https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:36:57 INFO - Downloading https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:36:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:36:58 INFO - Downloaded 21838825 bytes. 07:36:58 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'] 07:36:58 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 07:36:59 INFO - Return code: 0 07:36:59 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 07:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:36:59 INFO - https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:36:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:36:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:36:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:36:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:36:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:36:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:36:59 INFO - retry: Failed, sleeping 30 seconds before retrying 07:37:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:37:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:37:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:37:30 INFO - retry: Failed, sleeping 60 seconds before retrying 07:38:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:38:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:38:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:38:30 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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! 07:38:30 INFO - Caught exception: HTTP Error 404: Not Found 07:38:30 INFO - Caught exception: HTTP Error 404: Not Found 07:38:30 INFO - Caught exception: HTTP Error 404: Not Found 07:38:30 INFO - trying https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 07:38:30 INFO - Downloading https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:38:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:38:30 INFO - Downloaded 21838825 bytes. 07:38:30 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'] 07:38:30 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 07:38:32 INFO - Return code: 0 07:38: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')]} 07:38:32 INFO - https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:38:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:38:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:38:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:38:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:38:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:38:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:38:32 INFO - retry: Failed, sleeping 30 seconds before retrying 07:39:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:39:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:39:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:39:02 INFO - retry: Failed, sleeping 60 seconds before retrying 07:40:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:40:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:40:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:40:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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! 07:40:02 INFO - Caught exception: HTTP Error 404: Not Found 07:40:02 INFO - Caught exception: HTTP Error 404: Not Found 07:40:02 INFO - Caught exception: HTTP Error 404: Not Found 07:40:02 INFO - trying https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 07:40:02 INFO - Downloading https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:40:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:40:03 INFO - Downloaded 14413740 bytes. 07:40:03 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'] 07:40:03 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 07:40:03 INFO - Return code: 0 07:40:03 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:40:03 INFO - https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:40:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:40:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:40:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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:40:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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:40:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:40:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 07:40:03 INFO - retry: Failed, sleeping 30 seconds before retrying 07:40:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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 07:40:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:40:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 07:40:33 INFO - retry: Failed, sleeping 60 seconds before retrying 07:41:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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:41:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:41:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 07:41:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRc_JytXSZepOmvw-0WyWg/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:41:33 INFO - Caught exception: HTTP Error 404: Not Found 07:41:33 INFO - Caught exception: HTTP Error 404: Not Found 07:41:33 INFO - Caught exception: HTTP Error 404: Not Found 07:41:33 INFO - trying https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:41:33 INFO - Downloading https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/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:41:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/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:41:34 INFO - Downloaded 63885755 bytes. 07:41:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:41:34 INFO - mkdir: /builds/slave/test/properties 07:41:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:41:34 INFO - Writing to file /builds/slave/test/properties/build_url 07:41:34 INFO - Contents: 07:41:34 INFO - build_url:https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 07:41:35 INFO - Running post-action listener: _resource_record_post_action 07:41:35 INFO - Running post-action listener: set_extra_try_arguments 07:41:35 INFO - [mozharness: 2016-09-02 14:41:35.327289Z] Finished download-and-extract step (success) 07:41:35 INFO - [mozharness: 2016-09-02 14:41:35.327522Z] Running populate-webroot step. 07:41:35 INFO - Running pre-action listener: _resource_record_pre_action 07:41:35 INFO - Running main action method: populate_webroot 07:41:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:41:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:41:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:41:35 INFO - '--tppagecycles', 07:41:35 INFO - '7'], 07:41:35 INFO - 'tests': ['tsvgm']}}, 07:41:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:41:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:41:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:41:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:41:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:41:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:41:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:35 INFO - 'talos_options': ['--disable-e10s'], 07:41:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:41:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:41:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:35 INFO - 'talos_options': ['--disable-e10s'], 07:41:35 INFO - 'tests': ['damp', 'tps']}, 07:41:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:35 INFO - 'tests': ['damp', 'tps']}, 07:41:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:41:35 INFO - 'tests': ['dromaeo_dom']}, 07:41:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:41:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:41:35 INFO - 'tests': ['basic_compositor_video']}, 07:41:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:41:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:41:35 INFO - 'tests': ['a11yr', 07:41:35 INFO - 'ts_paint', 07:41:35 INFO - 'tpaint', 07:41:35 INFO - 'sessionrestore', 07:41:35 INFO - 'sessionrestore_no_auto_restore', 07:41:35 INFO - 'tabpaint']}, 07:41:35 INFO - 'other-e10s': {'tests': ['a11yr', 07:41:35 INFO - 'ts_paint', 07:41:35 INFO - 'tpaint', 07:41:35 INFO - 'sessionrestore', 07:41:35 INFO - 'sessionrestore_no_auto_restore', 07:41:35 INFO - 'tabpaint']}, 07:41:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:41:35 INFO - 'ts_paint', 07:41:35 INFO - 'tpaint', 07:41:35 INFO - 'sessionrestore', 07:41:35 INFO - 'sessionrestore_no_auto_restore', 07:41:35 INFO - 'tabpaint']}, 07:41:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:41:35 INFO - 'ts_paint', 07:41:35 INFO - 'tpaint', 07:41:35 INFO - 'sessionrestore', 07:41:35 INFO - 'sessionrestore_no_auto_restore', 07:41:35 INFO - 'tabpaint']}, 07:41:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:41:35 INFO - 'tests': ['a11yr', 07:41:35 INFO - 'ts_paint', 07:41:35 INFO - 'tpaint', 07:41:35 INFO - 'sessionrestore', 07:41:35 INFO - 'sessionrestore_no_auto_restore', 07:41:35 INFO - 'tabpaint']}, 07:41:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:41:35 INFO - 'tests': ['a11yr', 07:41:35 INFO - 'ts_paint', 07:41:35 INFO - 'tpaint', 07:41:35 INFO - 'sessionrestore', 07:41:35 INFO - 'sessionrestore_no_auto_restore', 07:41:35 INFO - 'tabpaint']}, 07:41:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:41:35 INFO - 'tests': ['tsvgx', 07:41:35 INFO - 'tsvgr_opacity', 07:41:35 INFO - 'tart', 07:41:35 INFO - 'tscrollx', 07:41:35 INFO - 'cart']}, 07:41:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:41:35 INFO - 'tsvgr_opacity', 07:41:35 INFO - 'tart', 07:41:35 INFO - 'tscrollx', 07:41:35 INFO - 'cart']}, 07:41:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:35 INFO - 'talos_options': ['--disable-e10s'], 07:41:35 INFO - 'tests': ['tp5o']}, 07:41:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:35 INFO - 'tests': ['tp5o']}, 07:41:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:35 INFO - 'talos_options': ['--disable-e10s', 07:41:35 INFO - '--xperf_path', 07:41:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:41:35 INFO - 'tests': ['tp5n']}, 07:41:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:41:35 INFO - 'talos_options': ['--xperf_path', 07:41:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:41:35 INFO - 'tests': ['tp5n']}}, 07:41:35 INFO - 'talos.zip': {'path': '', 07:41:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:41:35 INFO - Running post-action listener: _resource_record_post_action 07:41:35 INFO - [mozharness: 2016-09-02 14:41:35.349788Z] Finished populate-webroot step (success) 07:41:35 INFO - [mozharness: 2016-09-02 14:41:35.349878Z] Running create-virtualenv step. 07:41:35 INFO - Running pre-action listener: _resource_record_pre_action 07:41:35 INFO - Running main action method: create_virtualenv 07:41:35 INFO - Creating virtualenv /builds/slave/test/build/venv 07:41:35 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:41:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:41:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:41:35 INFO - Using real prefix '/usr' 07:41:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:41:35 INFO - Installing distribute.............................................................................................................................................................................................done. 07:41:37 INFO - Installing pip.................done. 07:41:37 INFO - Return code: 0 07:41:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:41:37 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:41:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:37 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:41:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:37 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 0x19d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c98180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c963b0>, '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 0x1c972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c97740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', '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:41:37 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:41:37 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:41:37 INFO - Using env: {'DISPLAY': ':0', 07:41:37 INFO - 'HOME': '/home/cltbld', 07:41:37 INFO - 'LANG': 'en_US.UTF-8', 07:41:37 INFO - 'LANGUAGE': 'en_US:en', 07:41:37 INFO - 'LOGNAME': 'cltbld', 07:41:37 INFO - 'MAIL': '/var/mail/cltbld', 07:41:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:37 INFO - 'MOZ_NO_REMOTE': '1', 07:41:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:37 INFO - 'NO_EM_RESTART': '1', 07:41:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:37 INFO - 'PWD': '/builds/slave/test', 07:41:37 INFO - 'SHELL': '/bin/bash', 07:41:37 INFO - 'SHLVL': '1', 07:41:37 INFO - 'TERM': 'linux', 07:41:37 INFO - 'TMOUT': '86400', 07:41:37 INFO - 'USER': 'cltbld', 07:41:37 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', 07:41:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:37 INFO - '_': '/tools/buildbot/bin/python'} 07:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:38 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:41:38 INFO - Cleaning up... 07:41:38 INFO - Return code: 0 07:41:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:38 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 0x19d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c98180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c963b0>, '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 0x1c972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c97740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', '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:41:38 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:41:38 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:41:38 INFO - Using env: {'DISPLAY': ':0', 07:41:38 INFO - 'HOME': '/home/cltbld', 07:41:38 INFO - 'LANG': 'en_US.UTF-8', 07:41:38 INFO - 'LANGUAGE': 'en_US:en', 07:41:38 INFO - 'LOGNAME': 'cltbld', 07:41:38 INFO - 'MAIL': '/var/mail/cltbld', 07:41:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:38 INFO - 'MOZ_NO_REMOTE': '1', 07:41:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:38 INFO - 'NO_EM_RESTART': '1', 07:41:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:38 INFO - 'PWD': '/builds/slave/test', 07:41:38 INFO - 'SHELL': '/bin/bash', 07:41:38 INFO - 'SHLVL': '1', 07:41:38 INFO - 'TERM': 'linux', 07:41:38 INFO - 'TMOUT': '86400', 07:41:38 INFO - 'USER': 'cltbld', 07:41:38 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', 07:41:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:38 INFO - '_': '/tools/buildbot/bin/python'} 07:41:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:38 INFO - Downloading/unpacking psutil>=3.1.1 07:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:41:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:41:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:41:40 INFO - Installing collected packages: psutil 07:41:40 INFO - Running setup.py install for psutil 07:41:40 INFO - building 'psutil._psutil_linux' extension 07:41:40 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:41:40 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:41:40 INFO - building 'psutil._psutil_posix' extension 07:41:40 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:41:40 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:41:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:41:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:41:40 INFO - Successfully installed psutil 07:41:40 INFO - Cleaning up... 07:41:40 INFO - Return code: 0 07:41:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:41:40 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:41:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:40 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:41:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:40 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 0x19d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c98180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c963b0>, '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 0x1c972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c97740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', '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:41:40 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:41:40 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:41:40 INFO - Using env: {'DISPLAY': ':0', 07:41:40 INFO - 'HOME': '/home/cltbld', 07:41:40 INFO - 'LANG': 'en_US.UTF-8', 07:41:40 INFO - 'LANGUAGE': 'en_US:en', 07:41:40 INFO - 'LOGNAME': 'cltbld', 07:41:40 INFO - 'MAIL': '/var/mail/cltbld', 07:41:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:40 INFO - 'MOZ_NO_REMOTE': '1', 07:41:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:40 INFO - 'NO_EM_RESTART': '1', 07:41:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:40 INFO - 'PWD': '/builds/slave/test', 07:41:40 INFO - 'SHELL': '/bin/bash', 07:41:40 INFO - 'SHLVL': '1', 07:41:40 INFO - 'TERM': 'linux', 07:41:40 INFO - 'TMOUT': '86400', 07:41:40 INFO - 'USER': 'cltbld', 07:41:40 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', 07:41:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:40 INFO - '_': '/tools/buildbot/bin/python'} 07:41:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:41:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:41:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:41:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:41:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:41:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:41:43 INFO - Installing collected packages: mozsystemmonitor 07:41:43 INFO - Running setup.py install for mozsystemmonitor 07:41:43 INFO - Successfully installed mozsystemmonitor 07:41:43 INFO - Cleaning up... 07:41:43 INFO - Return code: 0 07:41:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:41:43 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:41:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:43 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:41:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:43 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 0x19d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c98180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c963b0>, '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 0x1c972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c97740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', '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:41:43 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:41:43 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:41:43 INFO - Using env: {'DISPLAY': ':0', 07:41:43 INFO - 'HOME': '/home/cltbld', 07:41:43 INFO - 'LANG': 'en_US.UTF-8', 07:41:43 INFO - 'LANGUAGE': 'en_US:en', 07:41:43 INFO - 'LOGNAME': 'cltbld', 07:41:43 INFO - 'MAIL': '/var/mail/cltbld', 07:41:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:43 INFO - 'MOZ_NO_REMOTE': '1', 07:41:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:43 INFO - 'NO_EM_RESTART': '1', 07:41:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:43 INFO - 'PWD': '/builds/slave/test', 07:41:43 INFO - 'SHELL': '/bin/bash', 07:41:43 INFO - 'SHLVL': '1', 07:41:43 INFO - 'TERM': 'linux', 07:41:43 INFO - 'TMOUT': '86400', 07:41:43 INFO - 'USER': 'cltbld', 07:41:43 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', 07:41:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:43 INFO - '_': '/tools/buildbot/bin/python'} 07:41:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:43 INFO - Downloading/unpacking jsonschema==2.5.1 07:41:43 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:41:43 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:41:43 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:41:43 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:41:43 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:41:43 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:41:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:41:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:41:45 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:41:45 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:41:45 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:41:45 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:41:45 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:41:45 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:41:45 INFO - Downloading functools32-3.2.3-2.tar.gz 07:41:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:41:45 INFO - warning: no files found matching '*.txt' 07:41:45 INFO - no previously-included directories found matching 'build' 07:41:45 INFO - no previously-included directories found matching 'dist' 07:41:45 INFO - no previously-included directories found matching '.git*' 07:41:45 INFO - Installing collected packages: jsonschema, functools32 07:41:45 INFO - Running setup.py install for functools32 07:41:45 INFO - warning: no files found matching '*.txt' 07:41:45 INFO - no previously-included directories found matching 'build' 07:41:45 INFO - no previously-included directories found matching 'dist' 07:41:45 INFO - no previously-included directories found matching '.git*' 07:41:45 INFO - Successfully installed jsonschema functools32 07:41:45 INFO - Cleaning up... 07:41:45 INFO - Return code: 0 07:41:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:41:45 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:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:45 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:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:45 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 0x19d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c98180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c963b0>, '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 0x1c972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c97740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', '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:41:45 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:41:45 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:41:45 INFO - Using env: {'DISPLAY': ':0', 07:41:45 INFO - 'HOME': '/home/cltbld', 07:41:45 INFO - 'LANG': 'en_US.UTF-8', 07:41:45 INFO - 'LANGUAGE': 'en_US:en', 07:41:45 INFO - 'LOGNAME': 'cltbld', 07:41:45 INFO - 'MAIL': '/var/mail/cltbld', 07:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:45 INFO - 'MOZ_NO_REMOTE': '1', 07:41:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:45 INFO - 'NO_EM_RESTART': '1', 07:41:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:45 INFO - 'PWD': '/builds/slave/test', 07:41:45 INFO - 'SHELL': '/bin/bash', 07:41:45 INFO - 'SHLVL': '1', 07:41:45 INFO - 'TERM': 'linux', 07:41:45 INFO - 'TMOUT': '86400', 07:41:45 INFO - 'USER': 'cltbld', 07:41:45 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', 07:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:45 INFO - '_': '/tools/buildbot/bin/python'} 07:41:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:41:45 INFO - Cleaning up... 07:41:45 INFO - Return code: 0 07:41:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:41:45 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:41:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:45 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:41:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:45 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 0x19d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c98180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c963b0>, '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 0x1c972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c97740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', '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:41:45 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:41:45 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:41:45 INFO - Using env: {'DISPLAY': ':0', 07:41:45 INFO - 'HOME': '/home/cltbld', 07:41:45 INFO - 'LANG': 'en_US.UTF-8', 07:41:45 INFO - 'LANGUAGE': 'en_US:en', 07:41:45 INFO - 'LOGNAME': 'cltbld', 07:41:45 INFO - 'MAIL': '/var/mail/cltbld', 07:41:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:45 INFO - 'MOZ_NO_REMOTE': '1', 07:41:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:45 INFO - 'NO_EM_RESTART': '1', 07:41:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:45 INFO - 'PWD': '/builds/slave/test', 07:41:45 INFO - 'SHELL': '/bin/bash', 07:41:45 INFO - 'SHLVL': '1', 07:41:45 INFO - 'TERM': 'linux', 07:41:45 INFO - 'TMOUT': '86400', 07:41:45 INFO - 'USER': 'cltbld', 07:41:45 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', 07:41:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:45 INFO - '_': '/tools/buildbot/bin/python'} 07:41:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:46 INFO - Downloading/unpacking blobuploader==1.2.4 07:41:46 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:41:46 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:41:46 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:41:46 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:41:46 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:41:46 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:41:48 INFO - Downloading blobuploader-1.2.4.tar.gz 07:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:41:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:41:48 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:41:48 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:41:48 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:41:48 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:41:48 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:41:48 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:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:41:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:41:48 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:41:48 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:41:48 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:41:48 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:41:48 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:41:48 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:41:48 INFO - Downloading docopt-0.6.1.tar.gz 07:41:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:41:48 INFO - Installing collected packages: blobuploader, requests, docopt 07:41:48 INFO - Running setup.py install for blobuploader 07:41:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:41:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:41:48 INFO - Running setup.py install for requests 07:41:49 INFO - Running setup.py install for docopt 07:41:49 INFO - Successfully installed blobuploader requests docopt 07:41:49 INFO - Cleaning up... 07:41:49 INFO - Return code: 0 07:41:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:41:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:49 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 0x19d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c98180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c963b0>, '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 0x1c972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c97740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', '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:41:49 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:41:49 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:41:49 INFO - Using env: {'DISPLAY': ':0', 07:41:49 INFO - 'HOME': '/home/cltbld', 07:41:49 INFO - 'LANG': 'en_US.UTF-8', 07:41:49 INFO - 'LANGUAGE': 'en_US:en', 07:41:49 INFO - 'LOGNAME': 'cltbld', 07:41:49 INFO - 'MAIL': '/var/mail/cltbld', 07:41:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:49 INFO - 'MOZ_NO_REMOTE': '1', 07:41:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:49 INFO - 'NO_EM_RESTART': '1', 07:41:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:49 INFO - 'PWD': '/builds/slave/test', 07:41:49 INFO - 'SHELL': '/bin/bash', 07:41:49 INFO - 'SHLVL': '1', 07:41:49 INFO - 'TERM': 'linux', 07:41:49 INFO - 'TMOUT': '86400', 07:41:49 INFO - 'USER': 'cltbld', 07:41:49 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', 07:41:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:49 INFO - '_': '/tools/buildbot/bin/python'} 07:41:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:41:49 INFO - Running setup.py (path:/tmp/pip-V_8kh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:41:49 INFO - Running setup.py (path:/tmp/pip-bw80wc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:41:49 INFO - Running setup.py (path:/tmp/pip-lYdpm4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:41:49 INFO - Running setup.py (path:/tmp/pip-wLdyEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:41:49 INFO - Running setup.py (path:/tmp/pip-QkROqD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:41:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:41:49 INFO - Running setup.py (path:/tmp/pip-EgyH0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:41:50 INFO - Running setup.py (path:/tmp/pip-7krryR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:41:50 INFO - Running setup.py (path:/tmp/pip-7iI0UV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:41:50 INFO - Running setup.py (path:/tmp/pip-7SufYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:41:50 INFO - Running setup.py (path:/tmp/pip-JS6kpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:41:50 INFO - Running setup.py (path:/tmp/pip-0fV3Tj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:41:50 INFO - Running setup.py (path:/tmp/pip-y0lGKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:41:50 INFO - Running setup.py (path:/tmp/pip-QKRmIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:41:50 INFO - Running setup.py (path:/tmp/pip-Thi2Rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:41:50 INFO - Running setup.py (path:/tmp/pip-4te_KX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:41:50 INFO - Running setup.py (path:/tmp/pip-EcsiFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:41:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:41:50 INFO - Running setup.py (path:/tmp/pip-rNt3pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:41:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:41:50 INFO - Running setup.py install for manifestparser 07:41:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:41:50 INFO - Running setup.py install for mozcrash 07:41:51 INFO - Running setup.py install for mozdebug 07:41:51 INFO - Running setup.py install for mozdevice 07:41:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:41:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:41:51 INFO - Running setup.py install for mozfile 07:41:51 INFO - Running setup.py install for mozhttpd 07:41:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:41:51 INFO - Running setup.py install for mozinfo 07:41:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:41:51 INFO - Running setup.py install for mozInstall 07:41:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:41:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:41:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:41:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:41:51 INFO - Running setup.py install for mozleak 07:41:51 INFO - Running setup.py install for mozlog 07:41:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:41:51 INFO - Running setup.py install for moznetwork 07:41:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:41:52 INFO - Running setup.py install for mozprocess 07:41:52 INFO - Running setup.py install for mozprofile 07:41:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:41:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:41:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:41:52 INFO - Running setup.py install for mozrunner 07:41:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:41:52 INFO - Running setup.py install for mozscreenshot 07:41:52 INFO - Running setup.py install for moztest 07:41:52 INFO - Running setup.py install for mozversion 07:41:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:41:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:41:52 INFO - Cleaning up... 07:41:52 INFO - Return code: 0 07:41:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:41:52 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:41:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:52 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:41:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:52 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 0x19d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c98180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c963b0>, '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 0x1c972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c97740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', '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:41:52 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:41:52 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:41:52 INFO - Using env: {'DISPLAY': ':0', 07:41:52 INFO - 'HOME': '/home/cltbld', 07:41:52 INFO - 'LANG': 'en_US.UTF-8', 07:41:52 INFO - 'LANGUAGE': 'en_US:en', 07:41:52 INFO - 'LOGNAME': 'cltbld', 07:41:52 INFO - 'MAIL': '/var/mail/cltbld', 07:41:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:52 INFO - 'MOZ_NO_REMOTE': '1', 07:41:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:52 INFO - 'NO_EM_RESTART': '1', 07:41:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:52 INFO - 'PWD': '/builds/slave/test', 07:41:52 INFO - 'SHELL': '/bin/bash', 07:41:52 INFO - 'SHLVL': '1', 07:41:52 INFO - 'TERM': 'linux', 07:41:52 INFO - 'TMOUT': '86400', 07:41:52 INFO - 'USER': 'cltbld', 07:41:52 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', 07:41:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:52 INFO - '_': '/tools/buildbot/bin/python'} 07:41:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:41:52 INFO - Running setup.py (path:/tmp/pip-GYhbGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:41:52 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:41:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:41:52 INFO - Running setup.py (path:/tmp/pip-L6Lqyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:41:53 INFO - Running setup.py (path:/tmp/pip-zwjfi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:41:53 INFO - Running setup.py (path:/tmp/pip-LPWdlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:41:53 INFO - Running setup.py (path:/tmp/pip-ZNZv2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:41:53 INFO - Running setup.py (path:/tmp/pip-7ZW8gx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:41:53 INFO - Running setup.py (path:/tmp/pip-pCpcRm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:41:53 INFO - Running setup.py (path:/tmp/pip-N_PIOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:41:53 INFO - Running setup.py (path:/tmp/pip-iRlXgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:41:53 INFO - Running setup.py (path:/tmp/pip-Nzidn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:41:53 INFO - Running setup.py (path:/tmp/pip-ayixHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:41:53 INFO - Running setup.py (path:/tmp/pip-WT1BhO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:41:53 INFO - Running setup.py (path:/tmp/pip-I2uq0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:41:53 INFO - Running setup.py (path:/tmp/pip-nCS3Ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:41:53 INFO - Running setup.py (path:/tmp/pip-q2OWRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:41:53 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:41:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:41:53 INFO - Running setup.py (path:/tmp/pip-o4UkwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:41:54 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:41:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:41:54 INFO - Running setup.py (path:/tmp/pip-GE3cFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:41:54 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:41:54 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:41:54 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:41:54 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:41:54 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:41:54 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:41:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:41:54 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:41:54 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:41:54 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:41:54 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:41:54 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:41:54 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:41:55 INFO - Downloading blessings-1.6.tar.gz 07:41:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:41:56 INFO - Installing collected packages: blessings 07:41:56 INFO - Running setup.py install for blessings 07:41:56 INFO - Successfully installed blessings 07:41:56 INFO - Cleaning up... 07:41:56 INFO - Return code: 0 07:41:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:41:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:41:56 INFO - Reading from file tmpfile_stdout 07:41:56 INFO - Current package versions: 07:41:56 INFO - argparse == 1.2.1 07:41:56 INFO - blessings == 1.6 07:41:56 INFO - blobuploader == 1.2.4 07:41:56 INFO - docopt == 0.6.1 07:41:56 INFO - functools32 == 3.2.3-2 07:41:56 INFO - jsonschema == 2.5.1 07:41:56 INFO - manifestparser == 1.1 07:41:56 INFO - mozInstall == 1.12 07:41:56 INFO - mozcrash == 0.17 07:41:56 INFO - mozdebug == 0.1 07:41:56 INFO - mozdevice == 0.48 07:41:56 INFO - mozfile == 1.2 07:41:56 INFO - mozhttpd == 0.7 07:41:56 INFO - mozinfo == 0.9 07:41:56 INFO - mozleak == 0.1 07:41:56 INFO - mozlog == 3.3 07:41:56 INFO - moznetwork == 0.27 07:41:56 INFO - mozprocess == 0.23 07:41:56 INFO - mozprofile == 0.28 07:41:56 INFO - mozrunner == 6.12 07:41:56 INFO - mozscreenshot == 0.1 07:41:56 INFO - mozsystemmonitor == 0.3 07:41:56 INFO - moztest == 0.7 07:41:56 INFO - mozversion == 1.4 07:41:56 INFO - psutil == 3.1.1 07:41:56 INFO - requests == 1.2.3 07:41:56 INFO - wsgiref == 0.1.2 07:41:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:56 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 0x19d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c98180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c963b0>, '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 0x1c972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c97740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', '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:41:56 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:41:56 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:41:56 INFO - Using env: {'DISPLAY': ':0', 07:41:56 INFO - 'HOME': '/home/cltbld', 07:41:56 INFO - 'LANG': 'en_US.UTF-8', 07:41:56 INFO - 'LANGUAGE': 'en_US:en', 07:41:56 INFO - 'LOGNAME': 'cltbld', 07:41:56 INFO - 'MAIL': '/var/mail/cltbld', 07:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:56 INFO - 'MOZ_NO_REMOTE': '1', 07:41:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:56 INFO - 'NO_EM_RESTART': '1', 07:41:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:56 INFO - 'PWD': '/builds/slave/test', 07:41:56 INFO - 'SHELL': '/bin/bash', 07:41:56 INFO - 'SHLVL': '1', 07:41:56 INFO - 'TERM': 'linux', 07:41:56 INFO - 'TMOUT': '86400', 07:41:56 INFO - 'USER': 'cltbld', 07:41:56 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', 07:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:56 INFO - '_': '/tools/buildbot/bin/python'} 07:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:56 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:41:56 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:41:56 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:41:56 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:41:56 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:41:56 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:41:56 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:41:56 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:41:56 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:41:56 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:41:56 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:41:56 INFO - Cleaning up... 07:41:56 INFO - Return code: 0 07:41:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:41:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19d7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b3a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c98180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c963b0>, '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 0x1c972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c97740>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', '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:41:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:41:56 INFO - Using env: {'DISPLAY': ':0', 07:41:56 INFO - 'HOME': '/home/cltbld', 07:41:56 INFO - 'LANG': 'en_US.UTF-8', 07:41:56 INFO - 'LANGUAGE': 'en_US:en', 07:41:56 INFO - 'LOGNAME': 'cltbld', 07:41:56 INFO - 'MAIL': '/var/mail/cltbld', 07:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:41:56 INFO - 'MOZ_NO_REMOTE': '1', 07:41:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:41:56 INFO - 'NO_EM_RESTART': '1', 07:41:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:41:56 INFO - 'PWD': '/builds/slave/test', 07:41:56 INFO - 'SHELL': '/bin/bash', 07:41:56 INFO - 'SHLVL': '1', 07:41:56 INFO - 'TERM': 'linux', 07:41:56 INFO - 'TMOUT': '86400', 07:41:56 INFO - 'USER': 'cltbld', 07:41:56 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', 07:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:41:56 INFO - '_': '/tools/buildbot/bin/python'} 07:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:41:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:41:56 INFO - Cleaning up... 07:41:56 INFO - Return code: 0 07:41:56 INFO - Running post-action listener: _resource_record_post_action 07:41:56 INFO - Running post-action listener: _start_resource_monitoring 07:41:56 INFO - Starting resource monitoring. 07:41:56 INFO - [mozharness: 2016-09-02 14:41:56.919966Z] Finished create-virtualenv step (success) 07:41:56 INFO - [mozharness: 2016-09-02 14:41:56.920395Z] Running install step. 07:41:56 INFO - Running pre-action listener: _resource_record_pre_action 07:41:56 INFO - Running main action method: install 07:41:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:41:57 INFO - Reading from file tmpfile_stdout 07:41:57 INFO - Detecting whether we're running mozinstall >=1.0... 07:41:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:41:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:41:57 INFO - Reading from file tmpfile_stdout 07:41:57 INFO - Output received: 07:41:57 INFO - Usage: mozinstall [options] installer 07:41:57 INFO - Options: 07:41:57 INFO - -h, --help show this help message and exit 07:41:57 INFO - -d DEST, --destination=DEST 07:41:57 INFO - Directory to install application into. [default: 07:41:57 INFO - "/builds/slave/test"] 07:41:57 INFO - --app=APP Application being installed. [default: firefox] 07:41:57 INFO - mkdir: /builds/slave/test/build/application 07:41:57 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:41:57 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:42:13 INFO - Reading from file tmpfile_stdout 07:42:13 INFO - Output received: 07:42:13 INFO - /builds/slave/test/build/application/firefox/firefox 07:42:13 INFO - Running post-action listener: _resource_record_post_action 07:42:13 INFO - [mozharness: 2016-09-02 14:42:13.239441Z] Finished install step (success) 07:42:13 INFO - [mozharness: 2016-09-02 14:42:13.239716Z] Running run-tests step. 07:42:13 INFO - Running pre-action listener: _resource_record_pre_action 07:42:13 INFO - Running main action method: run_tests 07:42:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:42:13 INFO - Python 2.7.3 07:42:13 INFO - Return code: 0 07:42:13 INFO - grabbing minidump binary from tooltool 07:42: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:42:13 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 0x1c963b0>, '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 0x1c972b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1c97740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:42:13 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:42:13 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:42:13 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:42:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:42:13 INFO - Return code: 0 07:42:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:42:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:42:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:42:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:42:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:42:13 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/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:42:13 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/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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:42:13 INFO - Using env: {'DISPLAY': ':0', 07:42:13 INFO - 'HOME': '/home/cltbld', 07:42:13 INFO - 'LANG': 'en_US.UTF-8', 07:42:13 INFO - 'LANGUAGE': 'en_US:en', 07:42:13 INFO - 'LOGNAME': 'cltbld', 07:42:13 INFO - 'MAIL': '/var/mail/cltbld', 07:42:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:42:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:42:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:42:13 INFO - 'MOZ_NO_REMOTE': '1', 07:42:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:42:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:42:13 INFO - 'NO_EM_RESTART': '1', 07:42:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:42:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:42:13 INFO - 'PWD': '/builds/slave/test', 07:42:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:42:13 INFO - 'SHELL': '/bin/bash', 07:42:13 INFO - 'SHLVL': '1', 07:42:13 INFO - 'TERM': 'linux', 07:42:13 INFO - 'TMOUT': '86400', 07:42:13 INFO - 'USER': 'cltbld', 07:42:13 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031', 07:42:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:42:13 INFO - '_': '/tools/buildbot/bin/python'} 07:42:13 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/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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:42:13 INFO - mozversion application_buildid: 20160902045242 07:42:13 INFO - mozversion application_changeset: b16b9d1eea136bba9d512610e79700192be4e646 07:42:13 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:42:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:42:13 INFO - mozversion application_name: Firefox 07:42:13 INFO - mozversion application_remotingname: firefox-dev 07:42:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:42:13 INFO - mozversion application_vendor: Mozilla 07:42:13 INFO - mozversion application_version: 50.0a2 07:42:13 INFO - mozversion platform_buildid: 20160902045242 07:42:13 INFO - mozversion platform_changeset: b16b9d1eea136bba9d512610e79700192be4e646 07:42:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:42:13 INFO - mozversion platform_version: 50.0a2 07:42:13 INFO - using testdate: 1472827333 07:42:13 INFO - actual date: 1472827333 07:42:13 INFO - starting webserver on 'localhost:53642' 07:42:13 INFO - SUITE-START | Running 2 tests 07:42:13 INFO - TEST-START | dromaeo_css 07:42:13 INFO - operating with platform_type : linux_ 07:42:13 INFO - Initialising browser for dromaeo_css test... 07:42:13 INFO - TEST-INFO | started process 18061 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4Ih9f4/profile http://localhost:53642/getInfo.html) 07:42:17 INFO - PROCESS | 18061 | __metrics Screen width/height:1600/1200 07:42:17 INFO - PROCESS | 18061 | colorDepth:24 07:42:17 INFO - PROCESS | 18061 | Browser inner width/height: 1024/697 07:42:17 INFO - PROCESS | 18061 | __metrics 07:42:18 INFO - PROCESS | 18061 | 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:42:18 INFO - PROCESS | 18061 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:42:19 INFO - TEST-INFO | 18061: exit 0 07:42:19 INFO - Browser initialized. 07:42:19 INFO - Running cycle 1/1 for dromaeo_css test... 07:42:19 INFO - TEST-INFO | started process 18150 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4Ih9f4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:42:31 INFO - PROCESS | 18150 | RSS: Main: 144814080 07:42:31 INFO - PROCESS | 18150 | 07:43:22 INFO - PROCESS | 18150 | 1472827402657 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:45:31 INFO - PROCESS | 18150 | Cycle 1(1): loaded http://localhost:53642/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53642/tests/dromaeo/cssquery-ext.html) 07:45:33 INFO - PROCESS | 18150 | RSS: Main: 239652864 07:45:33 INFO - PROCESS | 18150 | 07:48:35 INFO - PROCESS | 18150 | Cycle 1(1): loaded http://localhost:53642/tests/dromaeo/cssquery-ext.html (next: http://localhost:53642/tests/dromaeo/cssquery-jquery.html) 07:48:35 INFO - PROCESS | 18150 | RSS: Main: 236351488 07:48:35 INFO - PROCESS | 18150 | 07:51:38 INFO - PROCESS | 18150 | Cycle 1(1): loaded http://localhost:53642/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53642/tests/dromaeo/cssquery-mootools.html) 07:51:38 INFO - PROCESS | 18150 | RSS: Main: 174690304 07:51:38 INFO - PROCESS | 18150 | 07:54:40 INFO - PROCESS | 18150 | Cycle 1(1): loaded http://localhost:53642/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53642/tests/dromaeo/cssquery-prototype.html) 07:54:41 INFO - PROCESS | 18150 | RSS: Main: 176476160 07:54:41 INFO - PROCESS | 18150 | 07:57:43 INFO - PROCESS | 18150 | Cycle 1(1): loaded http://localhost:53642/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53642/tests/dromaeo/cssquery-yui.html) 07:57:44 INFO - PROCESS | 18150 | RSS: Main: 174616576 07:57:44 INFO - PROCESS | 18150 | 08:00:46 INFO - PROCESS | 18150 | Cycle 1(1): loaded http://localhost:53642/tests/dromaeo/cssquery-yui.html (next: http://localhost:53642/tests/dromaeo/cssquery-dojo.html) 08:00:47 INFO - PROCESS | 18150 | RSS: Main: 265854976 08:00:47 INFO - PROCESS | 18150 | 08:00:47 INFO - PROCESS | 18150 | __start_tp_report 08:00:47 INFO - PROCESS | 18150 | _x_x_mozilla_page_load 08:00:47 INFO - PROCESS | 18150 | _x_x_mozilla_page_load_details 08:00:47 INFO - PROCESS | 18150 | |i|pagename|runs| 08:00:47 INFO - PROCESS | 18150 | |0;dojo.html;3229.4187046331663;3245.513173024046;3248.285377217013;3276.6231883333394;3290;5611.551075913927;5705.400932902043;5714.657120572758;5715.3141623005195;5723.998300297447;5608.775648974036;5670.829875103754;5680.147977803332;5684.488396044355;5723.027085395483;5015.07221164084;5065.670731402447;5076.6700164489375;5119.052975199581;5171.224316352558;5150.819721309755;5194.974025129869;5197.558207552363;5197.948020519803;5202.843914682547;5055.988802239548;5083.720395378253;5125.1287281162295;5139.357580302462;5147.7426128693605;5205.453427390145;5208.7916483341;5218.843434696927;5222.843314700565;5251;4905.068037072981;4941.332920055783;5005.599552035828;5110.335656364686;5123.180291153435;5409.459054094598;5423.430539793304;5440.537554307889;5455.509004189602;5517.586181036399;5898.970505147489;5943.227380440559;5973.074173503114;5983.52131829457;5986.610870293417;5811.4479124483105;5902.144189092601;5958.40415958405;6005.4595086442;6019.247594050744;5597.944020559824;5603.551715862721;5623.128415095626;5630.718464076821;5634.436556344414;6084.787032453843;6134.2332208473945;6136.693165341804;6139.447449729457;6148.9692551536955;5756.079027355603;5879.353271140171;5935.1987481690285;5937.079752638261;5953.077273022688;6080.731755816248;6088.906424016061;6104.572679912363;6116.327204007555;6118.479929205978;6054.515638748978;6055.394460553997;6055.8486037848525;6055.969720151459;6084.421979911901;5765.279340082489;5794.913076303858;5807.477327040502;6050.183225264533;6063.969680151571;4475.216837053503;4492.528284530143;4520.886977825515;4522.909541809146;4526.95473045272;4045;4054.5742697016976;4075.1645912587846;4085.71400001997;4171.831887071625;5781.566382521328;5785.797497087582;5786.739596718157;5792.160136780143;5795.695968407074;6095.75616975316;6098.69506524672;6104.328523862371;6116.48009919153;6125.448709616156;5567.220589117527;5674.943250567441;5709.800156994485;5715.571332150105;5784.305883294031;5262.184361423985;5267.078261304257;5269.262303277545;5269.736513174326;5271.314729085271;6262.5616206866;6299.5275354348605;6301.40136687014;6316;6321.241451025906;5875.177475153397;5962.701864906824;5989.221401217814;6001.459868611846;6074.058520929263;6200.534959877937;6242.750289988531;6246.437820596168;6248.062790581449;6253.405926436982;5931.466168044896;5933;5952.73212705438;5959.880802383841;5987.790427335022;5795.565332599987;5930.7924222652;5960.225170727777;6006.579539432198;6032.3967603239325;5906.645601263938;5928.851778705567;5932.110183472341;5933.940660593339;5958.7616495341435;5856.619319744203;5874.911876321773;5910.202122713378;5923.555733320104;5935.258092738408;5645.983722929912;5837.970810145922;5878.470937615635;5905.084711869667;5908.940910590839;5973.701314934323;6008.459238668541;6014.518838492998;6016.789412370546;6021.066734656135;5832.096025116114;5835.533157347317;5837.066069428871;5837.67892765902;5838.978178818605 08:00:47 INFO - PROCESS | 18150 | |1;ext.html;614.6066517428847;636.5003472274265;639.7984634840025;653.8986457099148;683.9487038472116;46275.76862115689;48378.27432588514;48396.999999999956;48435.515644843545;52680.47319526813;48960.75519622397;49420.75289623556;49457.7527112364;49487.50512494874;49550.25674614875;8583.570821458934;9186.356955013152;9392.530373481333;9407.52962351883;11022.283551569122;10818.350898946088;11329.490172942238;11354;11361.886381136206;11375.772484550303;11432.542698292058;11461.656150315504;11466.426678666034;11476.655700329004;11481.770364592703;11358.943205283962;11429.314241145557;11447.313161210312;11449.084073274118;11451.88548114525;11282.266652667551;11322;11366.09071274296;11369.829452558219;11371.204015718902;46254;46436.76781616104;46581.767091164664;46667;46695.76652116752;4006.3790112532606;4053.5338436079933;4122.793860306972;4160.54234034256;4793.5925446337105;34335.31329373398;34811.477827832605;35679.64320356815;36510;36951.63048369509;4550.089982003579;4609.3777340059;4635.721856688576;4665.650076244296;4700.835470758541;379390;379445;379653;379713;381149.09425452974;64866.351336486034;65303;65309.67345163339;65417;65445.34554654488;39942.80028599838;39981.60018399837;40064.799676001436;42615.14769704588;42677.786611066746;17986.2805487779;18207.726884096748;18214.63570728578;18215.362462313755;18223.45329640113;12593.62219133409;13360.2651854147;13660.52188173429;13717.245551494563;13762.518311859038;21250;21302.78697213039;21399.25102621432;21552.137914483246;21608.459788505097;7871.450627307734;7910.248526389983;7956.960215199003;7996.440249182618;8022.478538894953;1012.6708819633649;1068.871735391758;1100.9119270458343;1114.2478826791883;1139.0488941733745;35439.822800886344;35465.1133721659;35666.64333356685;35731.28537429237;35752.46371304432;1191.4459776204108;1192.9880701193051;1221.9755604887853;1240.7270400511873;1249.5314257153568;17758.733618995724;18419.447416577525;18593.44219673385;18726.344577939708;18742.437726868215;164882;166547;166562;166624;167421;62623.373766262666;62942.37057629365;63178.368216318166;63430;63549;1297.4226469221105;1423.1394896258594;1449.6593300574568;1461.5615315405337;1484.3765618440066;33696.83151584226;36037.81981090078;36427.81786091052;36897.81551092227;36943.81528092342;62366;63864;63929.3607063942;69434.30565694481;69605.30394695989;556282;563361;563731;563924;563952;30289.24276893095;31027.68972310248;31222.843885781334;32909.83545082259;33267.500987484724;53217;53330.46669533255;53334;53553.732231338596;53643.46356536541;43369;44014.339784903896;44494.332585010605;45693.08613827638;48699.75650121726;46130.538694612624;46540;46669;46822.53177468182;48224.27663584979;45109.32336015028;45368.09263814771;45580.544194558955;45780;45898.77050614726;38046.42930355992;39954.200915982095;40128.39807402833;42073.579264206965;42821.357679635454;29892.252693682833;30179.038342548367;30491;30540;30743 08:00:47 INFO - PROCESS | 18150 | |2;jquery.html;377.8658576205447;379.9753016053958;380.73158423311565;387.4769061766615;406.14101176012724;17993.550161245974;20259.189632414706;20317.288894888683;20535.897320513413;21308.8934555327;20450.181992720274;20636.484087897774;20951.37145885622;21125;21132.683009754826;5732.770689172438;5836.824895253128;5845.3862344453855;5888.411158884099;5897.587168898179;4424.40270563473;5889.293284805829;5976.252968378953;6324.083007963843;6599.93400065998;6681.933180668179;6682.231543372502;6693.531452798306;6694.899576506356;6697.799066028003;6406.583572067801;6560.0815885775555;6574.408303252706;6613.305602911673;6617.04052912325;5760.135979603093;5791.8552036199435;5830.4461076197695;5832.066869300891;5840.970795145997;25115.246542603752;25303.36741581457;25320.746792532023;25476.61785073207;25577.488450230892;2690.9865450672623;2770.473610014091;3133.060081975398;3443.4490481523085;3625.963740362589;15856.60358491047;17330;17893.552661183443;18179;18212.362567310214;1976.693612490052;2023.3929821053773;2147.7315335583053;2154.2244791874914;2189.0915270162864;99993.5000325001;100191.49904250432;100681;101329;105434.94565054328;18774.342897998504;19181.61636767257;19554.315598954254;19664.508387290432;19720.802791972183;35460.29079418397;35520;35696.28607427889;35777.82111089428;35865.64134358675;9938.602455901828;10384.428856412818;10535.525901334458;10616;11105.555777768961;8535.658573657109;8610.397272190887;8633.22300992901;8760.95619521911;8794.86807697885;9155.344949964436;9588.66439674608;9735.41587504752;9736.123748862636;9800.90199098014;3692.5199724036243;3701.9074523137137;3753.6246375362794;3794.79128647927;3800.7909564974193;938.6245501799335;1017.5878769098526;1045.2213101239624;1058.6612284069024;1081.6322450366913;20054.29809956674;20499.59000820005;20579;20581.48546286325;21081.89459052695;1026.7484466305652;1047.4134484688598;1072.6835583502886;1108.7394462301352;1144.433505414825;11057.778844423066;11583;11828.408579570987;11829.171957963032;11950.223235489666;513651;551325;565175;587054;601918;75134.24865751382;96177;99852;100912.49543752235;102839.485802572;1028.9691309260734;1033.4419413516616;1062.1237479079637;1066.1470823341297;1070.550368845087;19096.618067638847;19472.513187170433;19556.413307600797;19572.608547829248;19584;45925;46965.06069878652;47262.52737472581;47529.524704753894;48415.27377089417;64310.67844660747;64991;65007;65841.67079164574;66533.3346666527;8427;8550.74347769568;8556.18716221958;8612.397132200686;8706;12760.29818359999;13767.586972390844;13962.790558141432;14005.43978240859;14254;11909.940450297692;11912.34482103458;12030.75938481243;12045.036397088386;12325.445354959227;11705.356205408785;12070.517179312728;12160.148789584644;12247.510099595916;12307.569235077088;11733.413329333329;11733.941330293294;11932.701682458008;12116.939415302868;12140.575079872162;7768.339691126317;7969.641366138653;8145.551994640352;8244.134365891736;8407.663693452438;11869.821952670543;11885.346305953255;12425.13024088305;12579;12859.807102893652 08:00:47 INFO - PROCESS | 18150 | |3;mootools.html;1514.5304955463807;1567.4513920127954;1687.2154448181593;1831.0661562603068;1848.2237460266688;8510.489370637766;9134.817303653932;9146.862797058033;9150.954245228764;9158.084191580838;7886;8078.878816817738;8083.070446898609;8310.542920139389;8936.687215947442;2372.406898275431;2442.4016116051503;2464.8151388645833;2477.578811602032;2482.2925466242123;2387.450886296153;2400.0399840063938;2410.1564452441594;2417.7098748150247;2419.5402873454077;2451.5587194305017;2452.1785201957364;2454.987725061372;2455;2458.3485376375274;2393.7127544694663;2404.2546810488802;2422.4186195313173;2424.539337525873;2424.6363045543135;2375.406148462884;2380.357303528055;2402.495475950035;2402.615581506968;2403.5192961407615;8993.325500587482;9157.587908544132;9175.03662115475;9184.8622270666;9189.724308270763;5346.117890548055;5494.532964698005;5497.47773961477;5499.835004949858;5509.889802203974;3360.075979105741;3461.9826900865587;3502.6322236165347;3505.263894582141;3513.54323937889;1041.5573381312988;1083.8970297821772;1098.3300186885992;1121.3944469986197;1124.35349673937;12658.11393202467;12658.303793291365;12690.809637855347;12701.555445559454;12729.618111456672;4428.557144285545;4720.811167553259;4759.381280433521;4766.451858036301;4766.713997160182;10651.254412191072;10664.893351066545;10669.519871605797;10670.679879603624;10681.412522311191;5444.237806707064;5454.645448045864;5461.098918678453;5463.945360546423;5465.152901300305;4357.128574285156;4941.802327906916;4993.101241776515;5028.874278143076;5063.518965698253;4008.3987401889367;4092.7135100543273;4112.383142528644;4150.066235097079;4177.4778152730905;1274.5857596281246;1279.9232046077266;1283.7432513497338;1293.605450337656;1305.928173950423;1025.9384436933808;1066.8239740442896;1079.6760971708457;1080.233034545466;1081.5944020992129;6229.0967809667345;6273.686315684289;6380.3619638035825;6404.3595640436515;6423.036544518266;989.7525618595351;1045.2160879340495;1050.779336339362;1065.8454524094118;1074.0923919288189;4362.105768317488;4429.955700443019;4430.091831174602;4449.555044495589;4587.747673877902;10650.680479585624;10815.78368432627;10911.945440272748;10913.563457461612;10957.342559446299;10540.156787456985;10702.357858528514;10839.891601084046;10870.891291087146;10885.83671244932;8441.620127094173;8615.741527754051;8626.698065567674;8630.5253211074;8633.395662303705;6265.154204182377;6321.273053598711;6335.588186767845;6485.254195767548;6511.674416279262;10331.535080921227;10337.121344685786;10343.741406464898;10344.27590068718;10348.27562070678;9913.65302214419;10083.899161008589;10330.431826249629;10463.581456741646;10509.9474502627;7379.520331178671;7707.190744971922;8061.516309021477;8062.193780621891;8071.636776345196;8074.1522140176785;8326.916730832856;8347.373946953881;8724;8808.691695790616;7860.724874629361;7985.840283194188;8037.517748935083;8038.919610804051;8123;7890.210978902064;7929.5242285463055;8108.918910811052;8136.552489613128;8358;7403.296686814744;7505.3620442260835;7506.962465187639;7515.210902855121;7524.097108347033;5567.888642227052;5612.663240205601;5634.126710359901;5702.942970570407;5731.713414329351;5556.91664625039;5711.628744131618;5742.856428589319;5748.856278593069;5787.045137552425 08:00:47 INFO - PROCESS | 18150 | |4;prototype.html;338.59876046884443;339.1115277971714;345.7355123330651;347.7774224496321;348.1418303880933;5613.971930140348;5625.099984002555;5640.182173584828;5651.971740141299;5653.236813030239;5580.720963951807;5611.727250463558;5622.634528755628;5649.180868774025;5674.517665998395;3920.8235629396745;4110.26015317242;4324.459442569679;4341.479022517302;4355.433793606827;4317.740935543861;4332.176886391576;4334.501532323791;4336.56634336567;4356.542563030884;4283.3146696528565;4301.763403012833;4326.74039557626;4336.479622445311;4352.563653994174;4097.774622395767;4218.936715949248;4256.3189889617515;4285.892852678677;4306.332518459643;3782.9810850945846;3811.104390468238;3815.1225218199693;3843.846246150151;3843.9429156982055;5478.123500239943;5482.396936337;5487.5609951203805;5506.284183056217;5535.197396377543;4874.65877388583;4959.181735013718;4966.404031516205;5002.1746411842005;5045.747712614391;5741.942580574224;5782.653040817522;5786.218860453827;5843.357230704662;5869.50109240715;4276.978615106905;4283.31466965284;4283.421738065353;4283.91432171358;4299.914001719948;5751.539876809845;5858.414158584107;5905.911411328833;6086.695665216722;6154.907676384858;4151.086760912594;4180.331147016463;4185.890738954179;4228.302330115558;4228.9577104228565;4274.252005898955;4314.158739045856;4316.460442444694;4316.784160792011;4332.415123958288;4699;4917.434495033045;4945.03571803502;4951.183054795918;4956.4547899731;5810.88378232433;5855.648661080349;5899.616524925866;5903.085021821538;5910.970445147746;1469.1038466535406;1486.420296084528;1488.5311126995177;1491.1947548323783;1492.8880333974996;935.653808090997;970.8203982263304;972.2125078686287;977.2621670638622;986.3243678080395;5099.419180054195;5125.359330083739;5134.920357255318;5151.475776869307;5210.947890521046;5277.709725965032;5310.230016647564;5324.307839980777;5352.063388907003;5354.598405119632;4931.026975676402;5025.271335656383;5049.293098966147;5110.616703747234;5124.666896651705;2857.9285517862218;2872.9712702872703;2874.2957975296185;2881.538953767429;2910.4324656692165;5546.750396232179;5593.692346920877;5641.52047075995;5641.548676105902;5662.971685141631;5424.728763561806;5764.452377024176;6154.753809847556;6182.443580077815;6225.28409232935;6174.38256174379;6210.223722034746;6229.626222426578;6247.969409941878;6252.562320637512;5910.881782364415;5961.731722072604;6010.308814486215;6083.939160608337;6095.329513753572;5939.782344619429;5960.403959604005;5967.6419414835245;5975.641461512323;5980.744043750861;6000.639961602318;6057.757689692539;6093.482054025458;6127.938720612915;6296.6851657417865;4137.627613514798;4242.109157077049;4276.5723427656985;4297.79661694726;4332.985161899732;5902.4097590240635;6034.487068599218;6093.146959425772;6106.358832322542;6121.663308518075;5835.533157347317;5848.766049358148;5851.561132914963;5858.677772722541;5863.706814659165;5821.650700957956;5847.502962248256;5848.4151584841175;5879.853003674943;5906.4093590642315;5676.886462270814;5823.854403639943;5827.9708601458415;5842.503387212135;5892.2929248488745;5862.413758624103;5873.73568189424;5879.470847623564;5888;5893.145493903275;5478.424765399575;5726.9713651431475;5783.479486846204;5801.158831969426;5805.419458054161 08:00:47 INFO - PROCESS | 18150 | |5;yui.html;826.9710560130395;833.8540755367809;836.6778790165788;862.5040601654049;901.7430032440756;17170.914145429288;17952.820471795312;18428.81571184291;18445.90777046113;18450.723239151408;16674.33302667895;16805.91597042013;16854.410095646654;16867.24097415616;16934.915325423357;751.6504825256254;915.0437792506813;927.8376284150247;929.1080562659855;930.5393830054132;894.3739382432291;920.8480600700876;922.7047344849651;924.089771574998;929.2426672262085;937.3672770879663;937.9437233765962;938.1134827587932;938.3431597881477;939.3143005605878;772.7449941519285;933.0436302789627;933.5565606336969;933.7525555727703;937.5312343828086;677.986410316575;697.5012865800926;698.769406095987;698.9580625162506;704.0143798681835;16999.49001529956;17019.57451063721;17080.572985675335;17084.914575427167;17100.74348884768;15334.463293784776;15472.226388680521;15533.301001454962;15536.689266214613;15791.842081579267;13860.653483662889;14015.50945716895;14195.716085678332;14675.77986330206;14705.044172128884;4672.229082201433;4715.599174070208;4748.382710247645;4780.306855505932;4843.6851604645935;91363;92144.53927730318;92529.07470925272;95339;95924.52037739907;12357.814632780515;12723.55467558631;12810;12967.546135885388;13007.349632518337;24156.39609009789;24214;24214.273571792874;24226.152084676953;24226.152084677306;9395;9432.52837358143;9574.186194173433;9756.07317304855;9830.852537211947;4992.728308619642;5178.171492561172;5242.213667949838;5309.681419114865;5356.3572371315695;3691.3171063353366;3806.600306967784;3828.0812604974603;3837.175007373428;3841.865534706272;619.3280290884351;624.4317670919532;625.8967270400424;642.8392901774556;644.6712176789778;671.4930227678071;679.1544527063837;707.3563057617544;719.4028955966535;756.2210922749576;10289.073983341535;10293.53679084443;10360.529222710325;10366.585336586602;10539.63111291101;709.7515869445756;748.2442732839871;756.6632848382527;768.3430666779934;770.1720650300949;4925.1023584578725;4938.925916111261;4939.506049395031;4971.965209121749;4972.90120582201;78228.60885695649;78764;95893.0410695898;136753;138959;75538;86546.56726716326;108021;111407;118383;100668;104945.47527262315;105142;106759;108690;9470.384425012353;9696.175825054666;9738.513074346112;9740.269479789185;9828.5085745711;48364.516354836;49221.507784923124;57982.7100864493;59185.40814591971;60834;59864.70067649634;76815;78934;78976.21023789688;80685.59657201676;14521.636959076108;14782.48261310849;14795.334209960793;14889.851101488846;14961.476348327757;16571.25429355706;16656.666866662843;16785.160741963096;16945.66108677795;17104.401345952832;15203.847961520685;15465.304061317043;15594.844051559792;16125.596860078123;16474.42339518111;15841.52475425739;15863;16554;16948.576285592466;17099.658006840044;15703.293351798968;15725.213739313218;16789.664206716043;16994.06532640667;17016.14919254057;11921.284722916653;12563.869251767384;12663.738098550677;12882.227066376048;13198.934005329911;11436.684781249929;11958.760824783629;12635.241885486901;13066.490405880324;13452.1256118352 08:00:47 INFO - PROCESS | 18150 | __end_tp_report 08:00:47 INFO - PROCESS | 18150 | __start_cc_report 08:00:47 INFO - PROCESS | 18150 | _x_x_mozilla_cycle_collect,875 08:00:47 INFO - PROCESS | 18150 | __end_cc_report 08:00:47 INFO - PROCESS | 18150 | __startTimestamp1472828447233__endTimestamp 08:00:47 INFO - PROCESS | 18150 | 08:00:47 INFO - PROCESS | 18150 | ------- Summary: start ------- 08:00:47 INFO - PROCESS | 18150 | Number of tests: 6 08:00:47 INFO - PROCESS | 18150 | 08:00:47 INFO - PROCESS | 18150 | [#0] dojo.html Cycles:165 Average:5596.17 Median:5832.10 stddev:643.27 (11.0%) stddev-sans-first:617.87 08:00:47 INFO - PROCESS | 18150 | Values: 3229.4 3245.5 3248.3 3276.6 3290.0 5611.6 5705.4 5714.7 5715.3 5724.0 5608.8 5670.8 5680.1 5684.5 5723.0 5015.1 5065.7 5076.7 5119.1 5171.2 5150.8 5195.0 5197.6 5197.9 5202.8 5056.0 5083.7 5125.1 5139.4 5147.7 5205.5 5208.8 5218.8 5222.8 5251.0 4905.1 4941.3 5005.6 5110.3 5123.2 5409.5 5423.4 5440.5 5455.5 5517.6 5899.0 5943.2 5973.1 5983.5 5986.6 5811.4 5902.1 5958.4 6005.5 6019.2 5597.9 5603.6 5623.1 5630.7 5634.4 6084.8 6134.2 6136.7 6139.4 6149.0 5756.1 5879.4 5935.2 5937.1 5953.1 6080.7 6088.9 6104.6 6116.3 6118.5 6054.5 6055.4 6055.8 6056.0 6084.4 5765.3 5794.9 5807.5 6050.2 6064.0 4475.2 4492.5 4520.9 4522.9 4527.0 4045.0 4054.6 4075.2 4085.7 4171.8 5781.6 5785.8 5786.7 5792.2 5795.7 6095.8 6098.7 6104.3 6116.5 6125.4 5567.2 5674.9 5709.8 5715.6 5784.3 5262.2 5267.1 5269.3 5269.7 5271.3 6262.6 6299.5 6301.4 6316.0 6321.2 5875.2 5962.7 5989.2 6001.5 6074.1 6200.5 6242.8 6246.4 6248.1 6253.4 5931.5 5933.0 5952.7 5959.9 5987.8 5795.6 5930.8 5960.2 6006.6 6032.4 5906.6 5928.9 5932.1 5933.9 5958.8 5856.6 5874.9 5910.2 5923.6 5935.3 5646.0 5838.0 5878.5 5905.1 5908.9 5973.7 6008.5 6014.5 6016.8 6021.1 5832.1 5835.5 5837.1 5837.7 5839.0 08:00:47 INFO - PROCESS | 18150 | 08:00:47 INFO - PROCESS | 18150 | [#1] ext.html Cycles:180 Average:56591.63 Median:209597.76 stddev:107371.96 (51.2%) stddev-sans-first:107590.91 08:00:47 INFO - PROCESS | 18150 | Values: 614.6 636.5 639.8 653.9 683.9 46275.8 48378.3 48397.0 48435.5 52680.5 48960.8 49420.8 49457.8 49487.5 49550.3 8583.6 9186.4 9392.5 9407.5 11022.3 10818.4 11329.5 11354.0 11361.9 11375.8 11432.5 11461.7 11466.4 11476.7 11481.8 11358.9 11429.3 11447.3 11449.1 11451.9 11282.3 11322.0 11366.1 11369.8 11371.2 46254.0 46436.8 46581.8 46667.0 46695.8 4006.4 4053.5 4122.8 4160.5 4793.6 34335.3 34811.5 35679.6 36510.0 36951.6 4550.1 4609.4 4635.7 4665.7 4700.8 379390.0 379445.0 379653.0 379713.0 381149.1 64866.4 65303.0 65309.7 65417.0 65445.3 39942.8 39981.6 40064.8 42615.1 42677.8 17986.3 18207.7 18214.6 18215.4 18223.5 12593.6 13360.3 13660.5 13717.2 13762.5 21250.0 21302.8 21399.3 21552.1 21608.5 7871.5 7910.2 7957.0 7996.4 8022.5 1012.7 1068.9 1100.9 1114.2 1139.0 35439.8 35465.1 35666.6 35731.3 35752.5 1191.4 1193.0 1222.0 1240.7 1249.5 17758.7 18419.4 18593.4 18726.3 18742.4 164882.0 166547.0 166562.0 166624.0 167421.0 62623.4 62942.4 63178.4 63430.0 63549.0 1297.4 1423.1 1449.7 1461.6 1484.4 33696.8 36037.8 36427.8 36897.8 36943.8 62366.0 63864.0 63929.4 69434.3 69605.3 556282.0 563361.0 563731.0 563924.0 563952.0 30289.2 31027.7 31222.8 32909.8 33267.5 53217.0 53330.5 53334.0 53553.7 53643.5 43369.0 44014.3 44494.3 45693.1 48699.8 46130.5 46540.0 46669.0 46822.5 48224.3 45109.3 45368.1 45580.5 45780.0 45898.8 38046.4 39954.2 40128.4 42073.6 42821.4 29892.3 30179.0 30491.0 30540.0 30743.0 08:00:47 INFO - PROCESS | 18150 | 08:00:47 INFO - PROCESS | 18150 | [#2] jquery.html Cycles:180 Average:33729.75 Median:25312.06 stddev:92994.87 (367.4%) stddev-sans-first:93222.02 08:00:47 INFO - PROCESS | 18150 | Values: 377.9 380.0 380.7 387.5 406.1 17993.6 20259.2 20317.3 20535.9 21308.9 20450.2 20636.5 20951.4 21125.0 21132.7 5732.8 5836.8 5845.4 5888.4 5897.6 4424.4 5889.3 5976.3 6324.1 6599.9 6681.9 6682.2 6693.5 6694.9 6697.8 6406.6 6560.1 6574.4 6613.3 6617.0 5760.1 5791.9 5830.4 5832.1 5841.0 25115.2 25303.4 25320.7 25476.6 25577.5 2691.0 2770.5 3133.1 3443.4 3626.0 15856.6 17330.0 17893.6 18179.0 18212.4 1976.7 2023.4 2147.7 2154.2 2189.1 99993.5 100191.5 100681.0 101329.0 105434.9 18774.3 19181.6 19554.3 19664.5 19720.8 35460.3 35520.0 35696.3 35777.8 35865.6 9938.6 10384.4 10535.5 10616.0 11105.6 8535.7 8610.4 8633.2 8761.0 8794.9 9155.3 9588.7 9735.4 9736.1 9800.9 3692.5 3701.9 3753.6 3794.8 3800.8 938.6 1017.6 1045.2 1058.7 1081.6 20054.3 20499.6 20579.0 20581.5 21081.9 1026.7 1047.4 1072.7 1108.7 1144.4 11057.8 11583.0 11828.4 11829.2 11950.2 513651.0 551325.0 565175.0 587054.0 601918.0 75134.2 96177.0 99852.0 100912.5 102839.5 1029.0 1033.4 1062.1 1066.1 1070.6 19096.6 19472.5 19556.4 19572.6 19584.0 45925.0 46965.1 47262.5 47529.5 48415.3 64310.7 64991.0 65007.0 65841.7 66533.3 8427.0 8550.7 8556.2 8612.4 8706.0 12760.3 13767.6 13962.8 14005.4 14254.0 11909.9 11912.3 12030.8 12045.0 12325.4 11705.4 12070.5 12160.1 12247.5 12307.6 11733.4 11733.9 11932.7 12116.9 12140.6 7768.3 7969.6 8145.6 8244.1 8407.7 11869.8 11885.3 12425.1 12579.0 12859.8 08:00:47 INFO - PROCESS | 18150 | 08:00:47 INFO - PROCESS | 18150 | [#3] mootools.html Cycles:180 Average:5982.96 Median:4429.26 stddev:3323.26 (75.0%) stddev-sans-first:3315.61 08:00:47 INFO - PROCESS | 18150 | Values: 1514.5 1567.5 1687.2 1831.1 1848.2 8510.5 9134.8 9146.9 9151.0 9158.1 7886.0 8078.9 8083.1 8310.5 8936.7 2372.4 2442.4 2464.8 2477.6 2482.3 2387.5 2400.0 2410.2 2417.7 2419.5 2451.6 2452.2 2455.0 2455.0 2458.3 2393.7 2404.3 2422.4 2424.5 2424.6 2375.4 2380.4 2402.5 2402.6 2403.5 8993.3 9157.6 9175.0 9184.9 9189.7 5346.1 5494.5 5497.5 5499.8 5509.9 3360.1 3462.0 3502.6 3505.3 3513.5 1041.6 1083.9 1098.3 1121.4 1124.4 12658.1 12658.3 12690.8 12701.6 12729.6 4428.6 4720.8 4759.4 4766.5 4766.7 10651.3 10664.9 10669.5 10670.7 10681.4 5444.2 5454.6 5461.1 5463.9 5465.2 4357.1 4941.8 4993.1 5028.9 5063.5 4008.4 4092.7 4112.4 4150.1 4177.5 1274.6 1279.9 1283.7 1293.6 1305.9 1025.9 1066.8 1079.7 1080.2 1081.6 6229.1 6273.7 6380.4 6404.4 6423.0 989.8 1045.2 1050.8 1065.8 1074.1 4362.1 4430.0 4430.1 4449.6 4587.7 10650.7 10815.8 10911.9 10913.6 10957.3 10540.2 10702.4 10839.9 10870.9 10885.8 8441.6 8615.7 8626.7 8630.5 8633.4 6265.2 6321.3 6335.6 6485.3 6511.7 10331.5 10337.1 10343.7 10344.3 10348.3 9913.7 10083.9 10330.4 10463.6 10509.9 7379.5 7707.2 8061.5 8062.2 8071.6 8074.2 8326.9 8347.4 8724.0 8808.7 7860.7 7985.8 8037.5 8038.9 8123.0 7890.2 7929.5 8108.9 8136.6 8358.0 7403.3 7505.4 7507.0 7515.2 7524.1 5567.9 5612.7 5634.1 5702.9 5731.7 5556.9 5711.6 5742.9 5748.9 5787.0 08:00:47 INFO - PROCESS | 18150 | 08:00:47 INFO - PROCESS | 18150 | [#4] prototype.html Cycles:180 Average:4846.60 Median:5520.74 stddev:1436.46 (26.0%) stddev-sans-first:1400.07 08:00:47 INFO - PROCESS | 18150 | Values: 338.6 339.1 345.7 347.8 348.1 5614.0 5625.1 5640.2 5652.0 5653.2 5580.7 5611.7 5622.6 5649.2 5674.5 3920.8 4110.3 4324.5 4341.5 4355.4 4317.7 4332.2 4334.5 4336.6 4356.5 4283.3 4301.8 4326.7 4336.5 4352.6 4097.8 4218.9 4256.3 4285.9 4306.3 3783.0 3811.1 3815.1 3843.8 3843.9 5478.1 5482.4 5487.6 5506.3 5535.2 4874.7 4959.2 4966.4 5002.2 5045.7 5741.9 5782.7 5786.2 5843.4 5869.5 4277.0 4283.3 4283.4 4283.9 4299.9 5751.5 5858.4 5905.9 6086.7 6154.9 4151.1 4180.3 4185.9 4228.3 4229.0 4274.3 4314.2 4316.5 4316.8 4332.4 4699.0 4917.4 4945.0 4951.2 4956.5 5810.9 5855.6 5899.6 5903.1 5911.0 1469.1 1486.4 1488.5 1491.2 1492.9 935.7 970.8 972.2 977.3 986.3 5099.4 5125.4 5134.9 5151.5 5210.9 5277.7 5310.2 5324.3 5352.1 5354.6 4931.0 5025.3 5049.3 5110.6 5124.7 2857.9 2873.0 2874.3 2881.5 2910.4 5546.8 5593.7 5641.5 5641.5 5663.0 5424.7 5764.5 6154.8 6182.4 6225.3 6174.4 6210.2 6229.6 6248.0 6252.6 5910.9 5961.7 6010.3 6083.9 6095.3 5939.8 5960.4 5967.6 5975.6 5980.7 6000.6 6057.8 6093.5 6127.9 6296.7 4137.6 4242.1 4276.6 4297.8 4333.0 5902.4 6034.5 6093.1 6106.4 6121.7 5835.5 5848.8 5851.6 5858.7 5863.7 5821.7 5847.5 5848.4 5879.9 5906.4 5676.9 5823.9 5828.0 5842.5 5892.3 5862.4 5873.7 5879.5 5888.0 5893.1 5478.4 5727.0 5783.5 5801.2 5805.4 08:00:47 INFO - PROCESS | 18150 | 08:00:47 INFO - PROCESS | 18150 | [#5] yui.html Cycles:180 Average:22623.80 Median:4693.91 stddev:31891.67 (679.4%) stddev-sans-first:31939.14 08:00:47 INFO - PROCESS | 18150 | Values: 827.0 833.9 836.7 862.5 901.7 17170.9 17952.8 18428.8 18445.9 18450.7 16674.3 16805.9 16854.4 16867.2 16934.9 751.7 915.0 927.8 929.1 930.5 894.4 920.8 922.7 924.1 929.2 937.4 937.9 938.1 938.3 939.3 772.7 933.0 933.6 933.8 937.5 678.0 697.5 698.8 699.0 704.0 16999.5 17019.6 17080.6 17084.9 17100.7 15334.5 15472.2 15533.3 15536.7 15791.8 13860.7 14015.5 14195.7 14675.8 14705.0 4672.2 4715.6 4748.4 4780.3 4843.7 91363.0 92144.5 92529.1 95339.0 95924.5 12357.8 12723.6 12810.0 12967.5 13007.3 24156.4 24214.0 24214.3 24226.2 24226.2 9395.0 9432.5 9574.2 9756.1 9830.9 4992.7 5178.2 5242.2 5309.7 5356.4 3691.3 3806.6 3828.1 3837.2 3841.9 619.3 624.4 625.9 642.8 644.7 671.5 679.2 707.4 719.4 756.2 10289.1 10293.5 10360.5 10366.6 10539.6 709.8 748.2 756.7 768.3 770.2 4925.1 4938.9 4939.5 4972.0 4972.9 78228.6 78764.0 95893.0 136753.0 138959.0 75538.0 86546.6 108021.0 111407.0 118383.0 100668.0 104945.5 105142.0 106759.0 108690.0 9470.4 9696.2 9738.5 9740.3 9828.5 48364.5 49221.5 57982.7 59185.4 60834.0 59864.7 76815.0 78934.0 78976.2 80685.6 14521.6 14782.5 14795.3 14889.9 14961.5 16571.3 16656.7 16785.2 16945.7 17104.4 15203.8 15465.3 15594.8 16125.6 16474.4 15841.5 15863.0 16554.0 16948.6 17099.7 15703.3 15725.2 16789.7 16994.1 17016.1 11921.3 12563.9 12663.7 12882.2 13198.9 11436.7 11958.8 12635.2 13066.5 13452.1 08:00:47 INFO - PROCESS | 18150 | -------- Summary: end -------- 08:00:47 INFO - PROCESS | 18150 | 08:00:48 INFO - TEST-INFO | 18150: exit 0 08:00:48 INFO - TEST-OK | dromaeo_css | took 1114528ms 08:00:48 INFO - TEST-START | kraken 08:00:48 INFO - operating with platform_type : linux_ 08:00:48 INFO - Initialising browser for kraken test... 08:00:48 INFO - TEST-INFO | started process 18354 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpixEFEX/profile http://localhost:53642/getInfo.html) 08:00:52 INFO - PROCESS | 18354 | __metrics Screen width/height:1600/1200 08:00:52 INFO - PROCESS | 18354 | colorDepth:24 08:00:52 INFO - PROCESS | 18354 | Browser inner width/height: 1024/697 08:00:52 INFO - PROCESS | 18354 | __metrics 08:00:53 INFO - PROCESS | 18354 | 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" 08:00:53 INFO - PROCESS | 18354 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:00:54 INFO - TEST-INFO | 18354: exit 0 08:00:54 INFO - Browser initialized. 08:00:54 INFO - Running cycle 1/1 for kraken test... 08:00:54 INFO - TEST-INFO | started process 18443 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpixEFEX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:01:06 INFO - PROCESS | 18443 | RSS: Main: 145272832 08:01:06 INFO - PROCESS | 18443 | 08:01:08 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53642/tests/kraken/driver.html?testName=audio-beat-detection) 08:01:09 INFO - PROCESS | 18443 | RSS: Main: 191258624 08:01:09 INFO - PROCESS | 18443 | 08:01:12 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53642/tests/kraken/driver.html?testName=audio-dft) 08:01:12 INFO - PROCESS | 18443 | RSS: Main: 181796864 08:01:12 INFO - PROCESS | 18443 | 08:01:15 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53642/tests/kraken/driver.html?testName=audio-fft) 08:01:16 INFO - PROCESS | 18443 | RSS: Main: 269123584 08:01:16 INFO - PROCESS | 18443 | 08:01:18 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53642/tests/kraken/driver.html?testName=audio-oscillator) 08:01:18 INFO - PROCESS | 18443 | RSS: Main: 175325184 08:01:18 INFO - PROCESS | 18443 | 08:01:21 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53642/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:01:21 INFO - PROCESS | 18443 | RSS: Main: 176992256 08:01:21 INFO - PROCESS | 18443 | 08:01:24 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53642/tests/kraken/driver.html?testName=imaging-darkroom) 08:01:25 INFO - PROCESS | 18443 | RSS: Main: 212955136 08:01:25 INFO - PROCESS | 18443 | 08:01:28 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53642/tests/kraken/driver.html?testName=imaging-desaturate) 08:01:29 INFO - PROCESS | 18443 | RSS: Main: 211202048 08:01:29 INFO - PROCESS | 18443 | 08:01:31 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53642/tests/kraken/driver.html?testName=json-parse-financial) 08:01:32 INFO - PROCESS | 18443 | RSS: Main: 210911232 08:01:32 INFO - PROCESS | 18443 | 08:01:33 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53642/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:01:34 INFO - PROCESS | 18443 | RSS: Main: 175988736 08:01:34 INFO - PROCESS | 18443 | 08:01:36 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53642/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:01:36 INFO - PROCESS | 18443 | RSS: Main: 182435840 08:01:36 INFO - PROCESS | 18443 | 08:01:38 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53642/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:01:39 INFO - PROCESS | 18443 | RSS: Main: 185475072 08:01:39 INFO - PROCESS | 18443 | 08:01:41 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53642/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:01:42 INFO - PROCESS | 18443 | RSS: Main: 180477952 08:01:42 INFO - PROCESS | 18443 | 08:01:45 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53642/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:01:45 INFO - PROCESS | 18443 | RSS: Main: 184016896 08:01:45 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | Cycle 1(1): loaded http://localhost:53642/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53642/tests/kraken/driver.html?testName=ai-astar) 08:01:47 INFO - PROCESS | 18443 | RSS: Main: 235352064 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | __start_tp_report 08:01:47 INFO - PROCESS | 18443 | _x_x_mozilla_page_load 08:01:47 INFO - PROCESS | 18443 | _x_x_mozilla_page_load_details 08:01:47 INFO - PROCESS | 18443 | |i|pagename|runs| 08:01:47 INFO - PROCESS | 18443 | |0;ai-astar;101;112;111;111;111;110;110;108;111;111 08:01:47 INFO - PROCESS | 18443 | |1;audio-beat-detection;147;117;116;170;121;118;146;120;156;118 08:01:47 INFO - PROCESS | 18443 | |2;audio-dft;160;159;161;160;160;161;161;161;160;159 08:01:47 INFO - PROCESS | 18443 | |3;audio-fft;75;75;74;116;75;76;74;75;119;74 08:01:47 INFO - PROCESS | 18443 | |4;audio-oscillator;83;83;137;82;83;82;124;83;82;131 08:01:47 INFO - PROCESS | 18443 | |5;imaging-gaussian-blur;116;117;116;118;117;117;116;117;116;117 08:01:47 INFO - PROCESS | 18443 | |6;imaging-darkroom;146;146;145;146;146;145;145;146;146;145 08:01:47 INFO - PROCESS | 18443 | |7;imaging-desaturate;95;95;96;95;95;94;95;95;95;94 08:01:47 INFO - PROCESS | 18443 | |8;json-parse-financial;54;51;58;57;50;50;51;64;51;50 08:01:47 INFO - PROCESS | 18443 | |9;json-stringify-tinderbox;60;62;59;59;60;59;60;60;59;60 08:01:47 INFO - PROCESS | 18443 | |10;stanford-crypto-aes;74;73;72;71;73;79;72;72;72;76 08:01:47 INFO - PROCESS | 18443 | |11;stanford-crypto-ccm;125;119;126;118;117;117;117;116;160;125 08:01:47 INFO - PROCESS | 18443 | |12;stanford-crypto-pbkdf2;151;150;159;148;150;152;155;148;152;149 08:01:47 INFO - PROCESS | 18443 | |13;stanford-crypto-sha256-iterative;62;62;60;59;60;61;61;64;61;59 08:01:47 INFO - PROCESS | 18443 | __end_tp_report 08:01:47 INFO - PROCESS | 18443 | __start_cc_report 08:01:47 INFO - PROCESS | 18443 | _x_x_mozilla_cycle_collect,1585 08:01:47 INFO - PROCESS | 18443 | __end_cc_report 08:01:47 INFO - PROCESS | 18443 | __startTimestamp1472828507685__endTimestamp 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | ------- Summary: start ------- 08:01:47 INFO - PROCESS | 18443 | Number of tests: 14 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#0] ai-astar Cycles:10 Average:109.60 Median:111.00 stddev:3.20 (2.9%) stddev-sans-first:1.13 08:01:47 INFO - PROCESS | 18443 | Values: 101.0 112.0 111.0 111.0 111.0 110.0 110.0 108.0 111.0 111.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#1] audio-beat-detection Cycles:10 Average:132.90 Median:133.50 stddev:19.92 (14.9%) stddev-sans-first:20.46 08:01:47 INFO - PROCESS | 18443 | Values: 147.0 117.0 116.0 170.0 121.0 118.0 146.0 120.0 156.0 118.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#2] audio-dft Cycles:10 Average:160.20 Median:160.50 stddev:0.79 (0.5%) stddev-sans-first:0.83 08:01:47 INFO - PROCESS | 18443 | Values: 160.0 159.0 161.0 160.0 160.0 161.0 161.0 161.0 160.0 159.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#3] audio-fft Cycles:10 Average:83.30 Median:75.00 stddev:18.05 (24.1%) stddev-sans-first:18.89 08:01:47 INFO - PROCESS | 18443 | Values: 75.0 75.0 74.0 116.0 75.0 76.0 74.0 75.0 119.0 74.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#4] audio-oscillator Cycles:10 Average:97.00 Median:82.50 stddev:23.44 (28.4%) stddev-sans-first:24.31 08:01:47 INFO - PROCESS | 18443 | Values: 83.0 83.0 137.0 82.0 83.0 82.0 124.0 83.0 82.0 131.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 08:01:47 INFO - PROCESS | 18443 | Values: 116.0 117.0 116.0 118.0 117.0 117.0 116.0 117.0 116.0 117.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 08:01:47 INFO - PROCESS | 18443 | Values: 146.0 146.0 145.0 146.0 146.0 145.0 145.0 146.0 146.0 145.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 08:01:47 INFO - PROCESS | 18443 | Values: 95.0 95.0 96.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:52.50 stddev:4.70 (8.9%) stddev-sans-first:4.98 08:01:47 INFO - PROCESS | 18443 | Values: 54.0 51.0 58.0 57.0 50.0 50.0 51.0 64.0 51.0 50.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 08:01:47 INFO - PROCESS | 18443 | Values: 60.0 62.0 59.0 59.0 60.0 59.0 60.0 60.0 59.0 60.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#10] stanford-crypto-aes Cycles:10 Average:73.40 Median:73.00 stddev:2.41 (3.3%) stddev-sans-first:2.55 08:01:47 INFO - PROCESS | 18443 | Values: 74.0 73.0 72.0 71.0 73.0 79.0 72.0 72.0 72.0 76.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:122.00 stddev:13.22 (10.8%) stddev-sans-first:14.02 08:01:47 INFO - PROCESS | 18443 | Values: 125.0 119.0 126.0 118.0 117.0 117.0 117.0 116.0 160.0 125.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.40 Median:151.50 stddev:3.41 (2.2%) stddev-sans-first:3.61 08:01:47 INFO - PROCESS | 18443 | Values: 151.0 150.0 159.0 148.0 150.0 152.0 155.0 148.0 152.0 149.0 08:01:47 INFO - PROCESS | 18443 | 08:01:47 INFO - PROCESS | 18443 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.90 Median:61.00 stddev:1.52 (2.5%) stddev-sans-first:1.56 08:01:47 INFO - PROCESS | 18443 | Values: 62.0 62.0 60.0 59.0 60.0 61.0 61.0 64.0 61.0 59.0 08:01:47 INFO - PROCESS | 18443 | -------- Summary: end -------- 08:01:47 INFO - PROCESS | 18443 | 08:01:48 INFO - TEST-INFO | 18443: exit 0 08:01:48 INFO - TEST-OK | kraken | took 60729ms 08:01:48 INFO - SUITE-END | took 1175s 08:01:48 INFO - Completed test suite (00:19:35) 08:01:48 INFO - javascript benchmark 08:01:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3229.4187046331663, 3245.513173024046, 3248.285377217013, 3276.6231883333394, 3290.0, 5611.551075913927, 5705.400932902043, 5714.657120572758, 5715.3141623005195, 5723.998300297447, 5608.775648974036, 5670.829875103754, 5680.147977803332, 5684.488396044355, 5723.027085395483, 5015.07221164084, 5065.670731402447, 5076.6700164489375, 5119.052975199581, 5171.224316352558, 5150.819721309755, 5194.974025129869, 5197.558207552363, 5197.948020519803, 5202.843914682547, 5055.988802239548, 5083.720395378253, 5125.1287281162295, 5139.357580302462, 5147.7426128693605, 5205.453427390145, 5208.7916483341, 5218.843434696927, 5222.843314700565, 5251.0, 4905.068037072981, 4941.332920055783, 5005.599552035828, 5110.335656364686, 5123.180291153435, 5409.459054094598, 5423.430539793304, 5440.537554307889, 5455.509004189602, 5517.586181036399, 5898.970505147489, 5943.227380440559, 5973.074173503114, 5983.52131829457, 5986.610870293417, 5811.4479124483105, 5902.144189092601, 5958.40415958405, 6005.4595086442, 6019.247594050744, 5597.944020559824, 5603.551715862721, 5623.128415095626, 5630.718464076821, 5634.436556344414, 6084.787032453843, 6134.2332208473945, 6136.693165341804, 6139.447449729457, 6148.9692551536955, 5756.079027355603, 5879.353271140171, 5935.1987481690285, 5937.079752638261, 5953.077273022688, 6080.731755816248, 6088.906424016061, 6104.572679912363, 6116.327204007555, 6118.479929205978, 6054.515638748978, 6055.394460553997, 6055.8486037848525, 6055.969720151459, 6084.421979911901, 5765.279340082489, 5794.913076303858, 5807.477327040502, 6050.183225264533, 6063.969680151571, 4475.216837053503, 4492.528284530143, 4520.886977825515, 4522.909541809146, 4526.95473045272, 4045.0, 4054.5742697016976, 4075.1645912587846, 4085.71400001997, 4171.831887071625, 5781.566382521328, 5785.797497087582, 5786.739596718157, 5792.160136780143, 5795.695968407074, 6095.75616975316, 6098.69506524672, 6104.328523862371, 6116.48009919153, 6125.448709616156, 5567.220589117527, 5674.943250567441, 5709.800156994485, 5715.571332150105, 5784.305883294031, 5262.184361423985, 5267.078261304257, 5269.262303277545, 5269.736513174326, 5271.314729085271, 6262.5616206866, 6299.5275354348605, 6301.40136687014, 6316.0, 6321.241451025906, 5875.177475153397, 5962.701864906824, 5989.221401217814, 6001.459868611846, 6074.058520929263, 6200.534959877937, 6242.750289988531, 6246.437820596168, 6248.062790581449, 6253.405926436982, 5931.466168044896, 5933.0, 5952.73212705438, 5959.880802383841, 5987.790427335022, 5795.565332599987, 5930.7924222652, 5960.225170727777, 6006.579539432198, 6032.3967603239325, 5906.645601263938, 5928.851778705567, 5932.110183472341, 5933.940660593339, 5958.7616495341435, 5856.619319744203, 5874.911876321773, 5910.202122713378, 5923.555733320104, 5935.258092738408, 5645.983722929912, 5837.970810145922, 5878.470937615635, 5905.084711869667, 5908.940910590839, 5973.701314934323, 6008.459238668541, 6014.518838492998, 6016.789412370546, 6021.066734656135, 5832.096025116114, 5835.533157347317, 5837.066069428871, 5837.67892765902, 5838.978178818605], "value": 5552.121636635998, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [614.6066517428847, 636.5003472274265, 639.7984634840025, 653.8986457099148, 683.9487038472116, 46275.76862115689, 48378.27432588514, 48396.999999999956, 48435.515644843545, 52680.47319526813, 48960.75519622397, 49420.75289623556, 49457.7527112364, 49487.50512494874, 49550.25674614875, 8583.570821458934, 9186.356955013152, 9392.530373481333, 9407.52962351883, 11022.283551569122, 10818.350898946088, 11329.490172942238, 11354.0, 11361.886381136206, 11375.772484550303, 11432.542698292058, 11461.656150315504, 11466.426678666034, 11476.655700329004, 11481.770364592703, 11358.943205283962, 11429.314241145557, 11447.313161210312, 11449.084073274118, 11451.88548114525, 11282.266652667551, 11322.0, 11366.09071274296, 11369.829452558219, 11371.204015718902, 46254.0, 46436.76781616104, 46581.767091164664, 46667.0, 46695.76652116752, 4006.3790112532606, 4053.5338436079933, 4122.793860306972, 4160.54234034256, 4793.5925446337105, 34335.31329373398, 34811.477827832605, 35679.64320356815, 36510.0, 36951.63048369509, 4550.089982003579, 4609.3777340059, 4635.721856688576, 4665.650076244296, 4700.835470758541, 379390.0, 379445.0, 379653.0, 379713.0, 381149.09425452974, 64866.351336486034, 65303.0, 65309.67345163339, 65417.0, 65445.34554654488, 39942.80028599838, 39981.60018399837, 40064.799676001436, 42615.14769704588, 42677.786611066746, 17986.2805487779, 18207.726884096748, 18214.63570728578, 18215.362462313755, 18223.45329640113, 12593.62219133409, 13360.2651854147, 13660.52188173429, 13717.245551494563, 13762.518311859038, 21250.0, 21302.78697213039, 21399.25102621432, 21552.137914483246, 21608.459788505097, 7871.450627307734, 7910.248526389983, 7956.960215199003, 7996.440249182618, 8022.478538894953, 1012.6708819633649, 1068.871735391758, 1100.9119270458343, 1114.2478826791883, 1139.0488941733745, 35439.822800886344, 35465.1133721659, 35666.64333356685, 35731.28537429237, 35752.46371304432, 1191.4459776204108, 1192.9880701193051, 1221.9755604887853, 1240.7270400511873, 1249.5314257153568, 17758.733618995724, 18419.447416577525, 18593.44219673385, 18726.344577939708, 18742.437726868215, 164882.0, 166547.0, 166562.0, 166624.0, 167421.0, 62623.373766262666, 62942.37057629365, 63178.368216318166, 63430.0, 63549.0, 1297.4226469221105, 1423.1394896258594, 1449.6593300574568, 1461.5615315405337, 1484.3765618440066, 33696.83151584226, 36037.81981090078, 36427.81786091052, 36897.81551092227, 36943.81528092342, 62366.0, 63864.0, 63929.3607063942, 69434.30565694481, 69605.30394695989, 556282.0, 563361.0, 563731.0, 563924.0, 563952.0, 30289.24276893095, 31027.68972310248, 31222.843885781334, 32909.83545082259, 33267.500987484724, 53217.0, 53330.46669533255, 53334.0, 53553.732231338596, 53643.46356536541, 43369.0, 44014.339784903896, 44494.332585010605, 45693.08613827638, 48699.75650121726, 46130.538694612624, 46540.0, 46669.0, 46822.53177468182, 48224.27663584979, 45109.32336015028, 45368.09263814771, 45580.544194558955, 45780.0, 45898.77050614726, 38046.42930355992, 39954.200915982095, 40128.39807402833, 42073.579264206965, 42821.357679635454, 29892.252693682833, 30179.038342548367, 30491.0, 30540.0, 30743.0], "value": 21652.5689303048, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.8658576205447, 379.9753016053958, 380.73158423311565, 387.4769061766615, 406.14101176012724, 17993.550161245974, 20259.189632414706, 20317.288894888683, 20535.897320513413, 21308.8934555327, 20450.181992720274, 20636.484087897774, 20951.37145885622, 21125.0, 21132.683009754826, 5732.770689172438, 5836.824895253128, 5845.3862344453855, 5888.411158884099, 5897.587168898179, 4424.40270563473, 5889.293284805829, 5976.252968378953, 6324.083007963843, 6599.93400065998, 6681.933180668179, 6682.231543372502, 6693.531452798306, 6694.899576506356, 6697.799066028003, 6406.583572067801, 6560.0815885775555, 6574.408303252706, 6613.305602911673, 6617.04052912325, 5760.135979603093, 5791.8552036199435, 5830.4461076197695, 5832.066869300891, 5840.970795145997, 25115.246542603752, 25303.36741581457, 25320.746792532023, 25476.61785073207, 25577.488450230892, 2690.9865450672623, 2770.473610014091, 3133.060081975398, 3443.4490481523085, 3625.963740362589, 15856.60358491047, 17330.0, 17893.552661183443, 18179.0, 18212.362567310214, 1976.693612490052, 2023.3929821053773, 2147.7315335583053, 2154.2244791874914, 2189.0915270162864, 99993.5000325001, 100191.49904250432, 100681.0, 101329.0, 105434.94565054328, 18774.342897998504, 19181.61636767257, 19554.315598954254, 19664.508387290432, 19720.802791972183, 35460.29079418397, 35520.0, 35696.28607427889, 35777.82111089428, 35865.64134358675, 9938.602455901828, 10384.428856412818, 10535.525901334458, 10616.0, 11105.555777768961, 8535.658573657109, 8610.397272190887, 8633.22300992901, 8760.95619521911, 8794.86807697885, 9155.344949964436, 9588.66439674608, 9735.41587504752, 9736.123748862636, 9800.90199098014, 3692.5199724036243, 3701.9074523137137, 3753.6246375362794, 3794.79128647927, 3800.7909564974193, 938.6245501799335, 1017.5878769098526, 1045.2213101239624, 1058.6612284069024, 1081.6322450366913, 20054.29809956674, 20499.59000820005, 20579.0, 20581.48546286325, 21081.89459052695, 1026.7484466305652, 1047.4134484688598, 1072.6835583502886, 1108.7394462301352, 1144.433505414825, 11057.778844423066, 11583.0, 11828.408579570987, 11829.171957963032, 11950.223235489666, 513651.0, 551325.0, 565175.0, 587054.0, 601918.0, 75134.24865751382, 96177.0, 99852.0, 100912.49543752235, 102839.485802572, 1028.9691309260734, 1033.4419413516616, 1062.1237479079637, 1066.1470823341297, 1070.550368845087, 19096.618067638847, 19472.513187170433, 19556.413307600797, 19572.608547829248, 19584.0, 45925.0, 46965.06069878652, 47262.52737472581, 47529.524704753894, 48415.27377089417, 64310.67844660747, 64991.0, 65007.0, 65841.67079164574, 66533.3346666527, 8427.0, 8550.74347769568, 8556.18716221958, 8612.397132200686, 8706.0, 12760.29818359999, 13767.586972390844, 13962.790558141432, 14005.43978240859, 14254.0, 11909.940450297692, 11912.34482103458, 12030.75938481243, 12045.036397088386, 12325.445354959227, 11705.356205408785, 12070.517179312728, 12160.148789584644, 12247.510099595916, 12307.569235077088, 11733.413329333329, 11733.941330293294, 11932.701682458008, 12116.939415302868, 12140.575079872162, 7768.339691126317, 7969.641366138653, 8145.551994640352, 8244.134365891736, 8407.663693452438, 11869.821952670543, 11885.346305953255, 12425.13024088305, 12579.0, 12859.807102893652], "value": 10827.544204511776, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.5304955463807, 1567.4513920127954, 1687.2154448181593, 1831.0661562603068, 1848.2237460266688, 8510.489370637766, 9134.817303653932, 9146.862797058033, 9150.954245228764, 9158.084191580838, 7886.0, 8078.878816817738, 8083.070446898609, 8310.542920139389, 8936.687215947442, 2372.406898275431, 2442.4016116051503, 2464.8151388645833, 2477.578811602032, 2482.2925466242123, 2387.450886296153, 2400.0399840063938, 2410.1564452441594, 2417.7098748150247, 2419.5402873454077, 2451.5587194305017, 2452.1785201957364, 2454.987725061372, 2455.0, 2458.3485376375274, 2393.7127544694663, 2404.2546810488802, 2422.4186195313173, 2424.539337525873, 2424.6363045543135, 2375.406148462884, 2380.357303528055, 2402.495475950035, 2402.615581506968, 2403.5192961407615, 8993.325500587482, 9157.587908544132, 9175.03662115475, 9184.8622270666, 9189.724308270763, 5346.117890548055, 5494.532964698005, 5497.47773961477, 5499.835004949858, 5509.889802203974, 3360.075979105741, 3461.9826900865587, 3502.6322236165347, 3505.263894582141, 3513.54323937889, 1041.5573381312988, 1083.8970297821772, 1098.3300186885992, 1121.3944469986197, 1124.35349673937, 12658.11393202467, 12658.303793291365, 12690.809637855347, 12701.555445559454, 12729.618111456672, 4428.557144285545, 4720.811167553259, 4759.381280433521, 4766.451858036301, 4766.713997160182, 10651.254412191072, 10664.893351066545, 10669.519871605797, 10670.679879603624, 10681.412522311191, 5444.237806707064, 5454.645448045864, 5461.098918678453, 5463.945360546423, 5465.152901300305, 4357.128574285156, 4941.802327906916, 4993.101241776515, 5028.874278143076, 5063.518965698253, 4008.3987401889367, 4092.7135100543273, 4112.383142528644, 4150.066235097079, 4177.4778152730905, 1274.5857596281246, 1279.9232046077266, 1283.7432513497338, 1293.605450337656, 1305.928173950423, 1025.9384436933808, 1066.8239740442896, 1079.6760971708457, 1080.233034545466, 1081.5944020992129, 6229.0967809667345, 6273.686315684289, 6380.3619638035825, 6404.3595640436515, 6423.036544518266, 989.7525618595351, 1045.2160879340495, 1050.779336339362, 1065.8454524094118, 1074.0923919288189, 4362.105768317488, 4429.955700443019, 4430.091831174602, 4449.555044495589, 4587.747673877902, 10650.680479585624, 10815.78368432627, 10911.945440272748, 10913.563457461612, 10957.342559446299, 10540.156787456985, 10702.357858528514, 10839.891601084046, 10870.891291087146, 10885.83671244932, 8441.620127094173, 8615.741527754051, 8626.698065567674, 8630.5253211074, 8633.395662303705, 6265.154204182377, 6321.273053598711, 6335.588186767845, 6485.254195767548, 6511.674416279262, 10331.535080921227, 10337.121344685786, 10343.741406464898, 10344.27590068718, 10348.27562070678, 9913.65302214419, 10083.899161008589, 10330.431826249629, 10463.581456741646, 10509.9474502627, 7379.520331178671, 7707.190744971922, 8061.516309021477, 8062.193780621891, 8071.636776345196, 8074.1522140176785, 8326.916730832856, 8347.373946953881, 8724.0, 8808.691695790616, 7860.724874629361, 7985.840283194188, 8037.517748935083, 8038.919610804051, 8123.0, 7890.210978902064, 7929.5242285463055, 8108.918910811052, 8136.552489613128, 8358.0, 7403.296686814744, 7505.3620442260835, 7506.962465187639, 7515.210902855121, 7524.097108347033, 5567.888642227052, 5612.663240205601, 5634.126710359901, 5702.942970570407, 5731.713414329351, 5556.91664625039, 5711.628744131618, 5742.856428589319, 5748.856278593069, 5787.045137552425], "value": 4822.949167185878, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.59876046884443, 339.1115277971714, 345.7355123330651, 347.7774224496321, 348.1418303880933, 5613.971930140348, 5625.099984002555, 5640.182173584828, 5651.971740141299, 5653.236813030239, 5580.720963951807, 5611.727250463558, 5622.634528755628, 5649.180868774025, 5674.517665998395, 3920.8235629396745, 4110.26015317242, 4324.459442569679, 4341.479022517302, 4355.433793606827, 4317.740935543861, 4332.176886391576, 4334.501532323791, 4336.56634336567, 4356.542563030884, 4283.3146696528565, 4301.763403012833, 4326.74039557626, 4336.479622445311, 4352.563653994174, 4097.774622395767, 4218.936715949248, 4256.3189889617515, 4285.892852678677, 4306.332518459643, 3782.9810850945846, 3811.104390468238, 3815.1225218199693, 3843.846246150151, 3843.9429156982055, 5478.123500239943, 5482.396936337, 5487.5609951203805, 5506.284183056217, 5535.197396377543, 4874.65877388583, 4959.181735013718, 4966.404031516205, 5002.1746411842005, 5045.747712614391, 5741.942580574224, 5782.653040817522, 5786.218860453827, 5843.357230704662, 5869.50109240715, 4276.978615106905, 4283.31466965284, 4283.421738065353, 4283.91432171358, 4299.914001719948, 5751.539876809845, 5858.414158584107, 5905.911411328833, 6086.695665216722, 6154.907676384858, 4151.086760912594, 4180.331147016463, 4185.890738954179, 4228.302330115558, 4228.9577104228565, 4274.252005898955, 4314.158739045856, 4316.460442444694, 4316.784160792011, 4332.415123958288, 4699.0, 4917.434495033045, 4945.03571803502, 4951.183054795918, 4956.4547899731, 5810.88378232433, 5855.648661080349, 5899.616524925866, 5903.085021821538, 5910.970445147746, 1469.1038466535406, 1486.420296084528, 1488.5311126995177, 1491.1947548323783, 1492.8880333974996, 935.653808090997, 970.8203982263304, 972.2125078686287, 977.2621670638622, 986.3243678080395, 5099.419180054195, 5125.359330083739, 5134.920357255318, 5151.475776869307, 5210.947890521046, 5277.709725965032, 5310.230016647564, 5324.307839980777, 5352.063388907003, 5354.598405119632, 4931.026975676402, 5025.271335656383, 5049.293098966147, 5110.616703747234, 5124.666896651705, 2857.9285517862218, 2872.9712702872703, 2874.2957975296185, 2881.538953767429, 2910.4324656692165, 5546.750396232179, 5593.692346920877, 5641.52047075995, 5641.548676105902, 5662.971685141631, 5424.728763561806, 5764.452377024176, 6154.753809847556, 6182.443580077815, 6225.28409232935, 6174.38256174379, 6210.223722034746, 6229.626222426578, 6247.969409941878, 6252.562320637512, 5910.881782364415, 5961.731722072604, 6010.308814486215, 6083.939160608337, 6095.329513753572, 5939.782344619429, 5960.403959604005, 5967.6419414835245, 5975.641461512323, 5980.744043750861, 6000.639961602318, 6057.757689692539, 6093.482054025458, 6127.938720612915, 6296.6851657417865, 4137.627613514798, 4242.109157077049, 4276.5723427656985, 4297.79661694726, 4332.985161899732, 5902.4097590240635, 6034.487068599218, 6093.146959425772, 6106.358832322542, 6121.663308518075, 5835.533157347317, 5848.766049358148, 5851.561132914963, 5858.677772722541, 5863.706814659165, 5821.650700957956, 5847.502962248256, 5848.4151584841175, 5879.853003674943, 5906.4093590642315, 5676.886462270814, 5823.854403639943, 5827.9708601458415, 5842.503387212135, 5892.2929248488745, 5862.413758624103, 5873.73568189424, 5879.470847623564, 5888.0, 5893.145493903275, 5478.424765399575, 5726.9713651431475, 5783.479486846204, 5801.158831969426, 5805.419458054161], "value": 4388.092995703699, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [826.9710560130395, 833.8540755367809, 836.6778790165788, 862.5040601654049, 901.7430032440756, 17170.914145429288, 17952.820471795312, 18428.81571184291, 18445.90777046113, 18450.723239151408, 16674.33302667895, 16805.91597042013, 16854.410095646654, 16867.24097415616, 16934.915325423357, 751.6504825256254, 915.0437792506813, 927.8376284150247, 929.1080562659855, 930.5393830054132, 894.3739382432291, 920.8480600700876, 922.7047344849651, 924.089771574998, 929.2426672262085, 937.3672770879663, 937.9437233765962, 938.1134827587932, 938.3431597881477, 939.3143005605878, 772.7449941519285, 933.0436302789627, 933.5565606336969, 933.7525555727703, 937.5312343828086, 677.986410316575, 697.5012865800926, 698.769406095987, 698.9580625162506, 704.0143798681835, 16999.49001529956, 17019.57451063721, 17080.572985675335, 17084.914575427167, 17100.74348884768, 15334.463293784776, 15472.226388680521, 15533.301001454962, 15536.689266214613, 15791.842081579267, 13860.653483662889, 14015.50945716895, 14195.716085678332, 14675.77986330206, 14705.044172128884, 4672.229082201433, 4715.599174070208, 4748.382710247645, 4780.306855505932, 4843.6851604645935, 91363.0, 92144.53927730318, 92529.07470925272, 95339.0, 95924.52037739907, 12357.814632780515, 12723.55467558631, 12810.0, 12967.546135885388, 13007.349632518337, 24156.39609009789, 24214.0, 24214.273571792874, 24226.152084676953, 24226.152084677306, 9395.0, 9432.52837358143, 9574.186194173433, 9756.07317304855, 9830.852537211947, 4992.728308619642, 5178.171492561172, 5242.213667949838, 5309.681419114865, 5356.3572371315695, 3691.3171063353366, 3806.600306967784, 3828.0812604974603, 3837.175007373428, 3841.865534706272, 619.3280290884351, 624.4317670919532, 625.8967270400424, 642.8392901774556, 644.6712176789778, 671.4930227678071, 679.1544527063837, 707.3563057617544, 719.4028955966535, 756.2210922749576, 10289.073983341535, 10293.53679084443, 10360.529222710325, 10366.585336586602, 10539.63111291101, 709.7515869445756, 748.2442732839871, 756.6632848382527, 768.3430666779934, 770.1720650300949, 4925.1023584578725, 4938.925916111261, 4939.506049395031, 4971.965209121749, 4972.90120582201, 78228.60885695649, 78764.0, 95893.0410695898, 136753.0, 138959.0, 75538.0, 86546.56726716326, 108021.0, 111407.0, 118383.0, 100668.0, 104945.47527262315, 105142.0, 106759.0, 108690.0, 9470.384425012353, 9696.175825054666, 9738.513074346112, 9740.269479789185, 9828.5085745711, 48364.516354836, 49221.507784923124, 57982.7100864493, 59185.40814591971, 60834.0, 59864.70067649634, 76815.0, 78934.0, 78976.21023789688, 80685.59657201676, 14521.636959076108, 14782.48261310849, 14795.334209960793, 14889.851101488846, 14961.476348327757, 16571.25429355706, 16656.666866662843, 16785.160741963096, 16945.66108677795, 17104.401345952832, 15203.847961520685, 15465.304061317043, 15594.844051559792, 16125.596860078123, 16474.42339518111, 15841.52475425739, 15863.0, 16554.0, 16948.576285592466, 17099.658006840044, 15703.293351798968, 15725.213739313218, 16789.664206716043, 16994.06532640667, 17016.14919254057, 11921.284722916653, 12563.869251767384, 12663.738098550677, 12882.227066376048, 13198.934005329911, 11436.684781249929, 11958.760824783629, 12635.241885486901, 13066.490405880324, 13452.1256118352], "value": 8363.552318927583, "unit": "score"}], "name": "dromaeo_css", "value": 7829.860589177901, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 112.0, 111.0, 111.0, 111.0, 110.0, 110.0, 108.0, 111.0, 111.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 116.0, 170.0, 121.0, 118.0, 146.0, 120.0, 156.0, 118.0], "value": 132.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 161.0, 160.0, 160.0, 161.0, 161.0, 161.0, 160.0, 159.0], "value": 160.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 116.0, 75.0, 76.0, 74.0, 75.0, 119.0, 74.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 137.0, 82.0, 83.0, 82.0, 124.0, 83.0, 82.0, 131.0], "value": 97.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 118.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 58.0, 57.0, 50.0, 50.0, 51.0, 64.0, 51.0, 50.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 59.0, 59.0, 60.0, 59.0, 60.0, 60.0, 59.0, 60.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 72.0, 71.0, 73.0, 79.0, 72.0, 72.0, 72.0, 76.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 119.0, 126.0, 118.0, 117.0, 117.0, 117.0, 116.0, 160.0, 125.0], "value": 124.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 159.0, 148.0, 150.0, 152.0, 155.0, 148.0, 152.0, 149.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 60.0, 59.0, 60.0, 61.0, 61.0, 64.0, 61.0, 59.0], "value": 60.9, "unit": "score"}], "name": "kraken", "value": 1463.3000000000004, "alertThreshold": 2.0}]} 08:01:48 INFO - Return code: 0 08:01:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:01:48 INFO - # TBPL SUCCESS # 08:01:48 INFO - Running post-action listener: _resource_record_post_action 08:01:48 INFO - [mozharness: 2016-09-02 15:01:48.989077Z] Finished run-tests step (success) 08:01:48 INFO - Running post-run listener: _resource_record_post_run 08:01:49 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 325246976; Read time: 20; Write time: 450168 08:01:49 INFO - TinderboxPrint: CPU usage
13.0% 08:01:49 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 20 08:01:49 INFO - TinderboxPrint: I/O write bytes / time
325,246,976 / 450,168 08:01:49 INFO - TinderboxPrint: CPU idle
8,278.9 (87.0%) 08:01:49 INFO - TinderboxPrint: CPU user
1,186.6 (12.5%) 08:01:49 INFO - TinderboxPrint: Swap in / out
0 / 0 08:01:49 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78471168; Read time: 0; Write time: 195216 08:01:49 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 262144; Write bytes: 246362112; Read time: 12; Write time: 254892 08:01:49 INFO - Running post-run listener: _upload_blobber_files 08:01:49 INFO - Blob upload gear active. 08:01:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:01:49 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 08:01:49 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'] 08:01:49 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 08:01:50 INFO - (blobuploader) - INFO - Open directory for files ... 08:01:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:01:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:01:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:01:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:01:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:01:50 INFO - (blobuploader) - INFO - Done attempting. 08:01:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:01:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:01:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:01:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:01:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:01:55 INFO - (blobuploader) - INFO - Done attempting. 08:01:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:01:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:01:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:01:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:01:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:01:56 INFO - (blobuploader) - INFO - Done attempting. 08:01:56 INFO - (blobuploader) - INFO - Iteration through files over. 08:01:56 INFO - Return code: 0 08:01:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:01:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:01:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4796ed35fd45b3ccdb76deb23a0e41c819b15dff7cf00359437f50bf9ce997bb743a6d128feb514d04b34e3df48048709b5f3b742904ccd014a5f8b343d71df5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/91db2869c947e3086ce3ce28720d08c835226c9724fde29ebf03b9fb2c6ee2cc23e11aeb3b176085c3b35a58718dbad0cba767f6cb91efc18c0a0c2a437ed334", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/691fd9c561c16ceac93724b45d00d236db9f3c2e36af9936d69dae5d685b825829ec2b50af04c58ec87282a2eba7f665ba4663302c3a3fc0dd9d9f16fd284946"} 08:01:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:01:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:01:56 INFO - Contents: 08:01:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4796ed35fd45b3ccdb76deb23a0e41c819b15dff7cf00359437f50bf9ce997bb743a6d128feb514d04b34e3df48048709b5f3b742904ccd014a5f8b343d71df5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/91db2869c947e3086ce3ce28720d08c835226c9724fde29ebf03b9fb2c6ee2cc23e11aeb3b176085c3b35a58718dbad0cba767f6cb91efc18c0a0c2a437ed334", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/691fd9c561c16ceac93724b45d00d236db9f3c2e36af9936d69dae5d685b825829ec2b50af04c58ec87282a2eba7f665ba4663302c3a3fc0dd9d9f16fd284946"} 08:01:56 INFO - Running post-run listener: copy_logs_to_upload_dir 08:01:56 INFO - Copying logs to upload dir... 08:01:56 INFO - mkdir: /builds/slave/test/build/upload/logs 08:01:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1679.795003 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 59 secs) (at 2016-09-02 08:01:55.643893) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 08:01:55.647640) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031 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/4796ed35fd45b3ccdb76deb23a0e41c819b15dff7cf00359437f50bf9ce997bb743a6d128feb514d04b34e3df48048709b5f3b742904ccd014a5f8b343d71df5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/91db2869c947e3086ce3ce28720d08c835226c9724fde29ebf03b9fb2c6ee2cc23e11aeb3b176085c3b35a58718dbad0cba767f6cb91efc18c0a0c2a437ed334", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/691fd9c561c16ceac93724b45d00d236db9f3c2e36af9936d69dae5d685b825829ec2b50af04c58ec87282a2eba7f665ba4663302c3a3fc0dd9d9f16fd284946"} build_url:https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011254 build_url: 'https://queue.taskcluster.net/v1/task/ZRc_JytXSZepOmvw-0WyWg/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/4796ed35fd45b3ccdb76deb23a0e41c819b15dff7cf00359437f50bf9ce997bb743a6d128feb514d04b34e3df48048709b5f3b742904ccd014a5f8b343d71df5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/91db2869c947e3086ce3ce28720d08c835226c9724fde29ebf03b9fb2c6ee2cc23e11aeb3b176085c3b35a58718dbad0cba767f6cb91efc18c0a0c2a437ed334", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/691fd9c561c16ceac93724b45d00d236db9f3c2e36af9936d69dae5d685b825829ec2b50af04c58ec87282a2eba7f665ba4663302c3a3fc0dd9d9f16fd284946"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 08:01:55.681581) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 08:01:55.682101) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1472826808.900550-1885469031 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004448 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 08:01:55.767409) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 08:01:55.768023) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 08:01:55.768626) ========= ========= Total master_lag: 0.41 =========