builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1472897815.48 results: success (0) buildid: 20160902160050 builduid: b954e943d60047298c8c718863c8a0b0 revision: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:55.480153) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:55.480613) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:55.480904) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:55.495738) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:55.496080) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-03 03:16: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% 223M=0s 2016-09-03 03:16:55 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.075673 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:55.585478) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:55.585799) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015460 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:55.635469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-03 03:16:55.635822) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd --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 d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd --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=9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-03 03:16:55,943 truncating revision to first 12 chars 2016-09-03 03:16:55,943 Setting DEBUG logging. 2016-09-03 03:16:55,943 attempt 1/10 2016-09-03 03:16:55,943 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d35ac86c5271?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-03 03:16:57,103 unpacking tar archive at: mozilla-aurora-d35ac86c5271/testing/mozharness/ program finished with exit code 0 elapsedTime=1.518668 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-03 03:16:57.180934) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:57.181234) ========= script_repo_revision: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:57.181624) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:57.181898) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-03 03:16:57.194302) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-03 03:16:57.194618) ========= /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=9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:16:57 INFO - MultiFileLogger online at 20160903 03:16:57 in /builds/slave/test 03:16:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:16:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:16:57 INFO - {'append_to_log': False, 03:16:57 INFO - 'base_work_dir': '/builds/slave/test', 03:16:57 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:16:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:16:57 INFO - 'branch': 'Mozilla-Aurora', 03:16:57 INFO - 'buildbot_json_path': 'buildprops.json', 03:16:57 INFO - 'config_files': ('talos/linux_config.py',), 03:16:57 INFO - 'default_actions': ('clobber', 03:16:57 INFO - 'read-buildbot-config', 03:16:57 INFO - 'download-and-extract', 03:16:57 INFO - 'populate-webroot', 03:16:57 INFO - 'create-virtualenv', 03:16:57 INFO - 'install', 03:16:57 INFO - 'run-tests'), 03:16:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:16:57 INFO - 'download_minidump_stackwalk': True, 03:16:57 INFO - 'download_symbols': 'ondemand', 03:16:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:16:57 INFO - 'tooltool.py': '/tools/tooltool.py', 03:16:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:16:57 INFO - '/tools/misc-python/virtualenv.py')}, 03:16:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:16:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:16:57 INFO - 'installer_path': 'installer.exe', 03:16:57 INFO - 'log_level': 'info', 03:16:57 INFO - 'log_name': 'talos', 03:16:57 INFO - 'log_to_console': True, 03:16:57 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:16:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:16:57 INFO - 'opt_config_files': (), 03:16:57 INFO - 'pip_index': False, 03:16:57 INFO - 'sps_profile': False, 03:16:57 INFO - 'sps_profile_interval': 0, 03:16:57 INFO - 'suite': 'dromaeojs', 03:16:57 INFO - 'system_bits': '32', 03:16:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:16:57 INFO - 'title': 'talos-linux64-ix-013', 03:16:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:16:57 INFO - 'use_talos_json': True, 03:16:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:16:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:16:57 INFO - 'work_dir': 'build'} 03:16:57 INFO - [mozharness: 2016-09-03 10:16:57.604462Z] Running clobber step. 03:16:57 INFO - Running pre-action listener: _resource_record_pre_action 03:16:57 INFO - Running main action method: clobber 03:16:57 INFO - rmtree: /builds/slave/test/build 03:16:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:16:58 INFO - Running post-action listener: _resource_record_post_action 03:16:58 INFO - [mozharness: 2016-09-03 10:16:58.055221Z] Finished clobber step (success) 03:16:58 INFO - [mozharness: 2016-09-03 10:16:58.055321Z] Running read-buildbot-config step. 03:16:58 INFO - Running pre-action listener: _resource_record_pre_action 03:16:58 INFO - Running main action method: read_buildbot_config 03:16:58 INFO - Using buildbot properties: 03:16:58 INFO - { 03:16:58 INFO - "project": "", 03:16:58 INFO - "product": "firefox", 03:16:58 INFO - "script_repo_revision": "production", 03:16:58 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 03:16:58 INFO - "repository": "", 03:16:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 03:16:58 INFO - "buildid": "20160902160050", 03:16:58 INFO - "pgo_build": "True", 03:16:58 INFO - "basedir": "/builds/slave/test", 03:16:58 INFO - "buildnumber": 728, 03:16:58 INFO - "slavename": "talos-linux64-ix-013", 03:16:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:16:58 INFO - "platform": "ubuntu64_hw", 03:16:58 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 03:16:58 INFO - "branch": "mozilla-aurora", 03:16:58 INFO - "repo_path": "releases/mozilla-aurora", 03:16:58 INFO - "revision": "d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd", 03:16:58 INFO - "stage_platform": "linux64-pgo", 03:16:58 INFO - "builduid": "b954e943d60047298c8c718863c8a0b0", 03:16:58 INFO - "slavebuilddir": "test" 03:16:58 INFO - } 03:16:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 03:16:58 INFO - Running post-action listener: _resource_record_post_action 03:16:58 INFO - [mozharness: 2016-09-03 10:16:58.057756Z] Finished read-buildbot-config step (success) 03:16:58 INFO - [mozharness: 2016-09-03 10:16:58.057845Z] Running download-and-extract step. 03:16:58 INFO - Running pre-action listener: _resource_record_pre_action 03:16:58 INFO - Running main action method: download_and_extract 03:16:58 INFO - mkdir: /builds/slave/test/build/tests 03:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:16:58 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:16:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:16:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:16:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:16:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:16:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:16:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:16:58 INFO - retry: Failed, sleeping 30 seconds before retrying 03:17:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 03:17:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:17:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:17:28 INFO - retry: Failed, sleeping 60 seconds before retrying 03:18:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 03:18:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:18:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:18:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 03:18:28 INFO - Caught exception: HTTP Error 404: Not Found 03:18:28 INFO - Caught exception: HTTP Error 404: Not Found 03:18:28 INFO - Caught exception: HTTP Error 404: Not Found 03:18:28 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:18:28 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:18:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:18:28 INFO - Downloaded 1361 bytes. 03:18:28 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 03:18:28 INFO - Using the following test package requirements: 03:18:28 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:18:28 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 03:18:28 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 03:18:28 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:28 INFO - u'jsshell-linux-x86_64.zip'], 03:18:28 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 03:18:28 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 03:18:28 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 03:18:28 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 03:18:28 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 03:18:28 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 03:18:28 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 03:18:28 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:18:28 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:18:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:18:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:18:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:18:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:18:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:18:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:18:28 INFO - retry: Failed, sleeping 30 seconds before retrying 03:18:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:18:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:18:58 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:18:58 INFO - retry: Failed, sleeping 60 seconds before retrying 03:19:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:19:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:19:58 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:19:58 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:19:58 INFO - Caught exception: HTTP Error 404: Not Found 03:19:58 INFO - Caught exception: HTTP Error 404: Not Found 03:19:58 INFO - Caught exception: HTTP Error 404: Not Found 03:19:58 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:19:58 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:19:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:19:59 INFO - Downloaded 21839364 bytes. 03:19:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:19:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:20:00 INFO - Return code: 0 03:20:00 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:20:00 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:20:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:20:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:00 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:20:00 INFO - retry: Failed, sleeping 30 seconds before retrying 03:20:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 03:20:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:20:30 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:20:30 INFO - retry: Failed, sleeping 60 seconds before retrying 03:21:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 03:21:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:21:30 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:21:30 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 03:21:30 INFO - Caught exception: HTTP Error 404: Not Found 03:21:30 INFO - Caught exception: HTTP Error 404: Not Found 03:21:30 INFO - Caught exception: HTTP Error 404: Not Found 03:21:30 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:21:30 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 03:21:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:21:31 INFO - Downloaded 21839364 bytes. 03:21:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:21:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:21:33 INFO - Return code: 0 03:21:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:21:33 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:21:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:21:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:21:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:21:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:21:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:21:33 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:21:33 INFO - retry: Failed, sleeping 30 seconds before retrying 03:22:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 03:22:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:22:03 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:22:03 INFO - retry: Failed, sleeping 60 seconds before retrying 03:23:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 03:23:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:23:03 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:23:03 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 03:23:03 INFO - Caught exception: HTTP Error 404: Not Found 03:23:03 INFO - Caught exception: HTTP Error 404: Not Found 03:23:03 INFO - Caught exception: HTTP Error 404: Not Found 03:23:03 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:23:03 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 03:23:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:23:04 INFO - Downloaded 14413740 bytes. 03:23:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:23:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:23:04 INFO - Return code: 0 03:23:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:23:04 INFO - https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:23:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:23:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:23:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:23:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:23:04 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:23:04 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 03:23:04 INFO - retry: Failed, sleeping 30 seconds before retrying 03:23:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 03:23:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:23:34 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 03:23:34 INFO - retry: Failed, sleeping 60 seconds before retrying 03:24:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 03:24:34 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:34 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 03:24:34 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 03:24:34 INFO - Caught exception: HTTP Error 404: Not Found 03:24:34 INFO - Caught exception: HTTP Error 404: Not Found 03:24:34 INFO - Caught exception: HTTP Error 404: Not Found 03:24:34 INFO - trying https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:34 INFO - Downloading https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:24:35 INFO - Downloaded 63899078 bytes. 03:24:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:35 INFO - mkdir: /builds/slave/test/properties 03:24:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:24:35 INFO - Writing to file /builds/slave/test/properties/build_url 03:24:35 INFO - Contents: 03:24:35 INFO - build_url:https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 03:24:36 INFO - Running post-action listener: _resource_record_post_action 03:24:36 INFO - Running post-action listener: set_extra_try_arguments 03:24:36 INFO - [mozharness: 2016-09-03 10:24:36.183303Z] Finished download-and-extract step (success) 03:24:36 INFO - [mozharness: 2016-09-03 10:24:36.183535Z] Running populate-webroot step. 03:24:36 INFO - Running pre-action listener: _resource_record_pre_action 03:24:36 INFO - Running main action method: populate_webroot 03:24:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:24:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:24:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:24:36 INFO - '--tppagecycles', 03:24:36 INFO - '7'], 03:24:36 INFO - 'tests': ['tsvgm']}}, 03:24:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:24:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:24:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:24:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:24:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:24:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:24:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:36 INFO - 'talos_options': ['--disable-e10s'], 03:24:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:24:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:24:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:36 INFO - 'talos_options': ['--disable-e10s'], 03:24:36 INFO - 'tests': ['damp', 'tps']}, 03:24:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:36 INFO - 'tests': ['damp', 'tps']}, 03:24:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:24:36 INFO - 'tests': ['dromaeo_dom']}, 03:24:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:24:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:24:36 INFO - 'tests': ['basic_compositor_video']}, 03:24:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:24:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:24:36 INFO - 'tests': ['a11yr', 03:24:36 INFO - 'ts_paint', 03:24:36 INFO - 'tpaint', 03:24:36 INFO - 'sessionrestore', 03:24:36 INFO - 'sessionrestore_no_auto_restore', 03:24:36 INFO - 'tabpaint']}, 03:24:36 INFO - 'other-e10s': {'tests': ['a11yr', 03:24:36 INFO - 'ts_paint', 03:24:36 INFO - 'tpaint', 03:24:36 INFO - 'sessionrestore', 03:24:36 INFO - 'sessionrestore_no_auto_restore', 03:24:36 INFO - 'tabpaint']}, 03:24:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:24:36 INFO - 'ts_paint', 03:24:36 INFO - 'tpaint', 03:24:36 INFO - 'sessionrestore', 03:24:36 INFO - 'sessionrestore_no_auto_restore', 03:24:36 INFO - 'tabpaint']}, 03:24:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:24:36 INFO - 'ts_paint', 03:24:36 INFO - 'tpaint', 03:24:36 INFO - 'sessionrestore', 03:24:36 INFO - 'sessionrestore_no_auto_restore', 03:24:36 INFO - 'tabpaint']}, 03:24:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:24:36 INFO - 'tests': ['a11yr', 03:24:36 INFO - 'ts_paint', 03:24:36 INFO - 'tpaint', 03:24:36 INFO - 'sessionrestore', 03:24:36 INFO - 'sessionrestore_no_auto_restore', 03:24:36 INFO - 'tabpaint']}, 03:24:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:24:36 INFO - 'tests': ['a11yr', 03:24:36 INFO - 'ts_paint', 03:24:36 INFO - 'tpaint', 03:24:36 INFO - 'sessionrestore', 03:24:36 INFO - 'sessionrestore_no_auto_restore', 03:24:36 INFO - 'tabpaint']}, 03:24:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:24:36 INFO - 'tests': ['tsvgx', 03:24:36 INFO - 'tsvgr_opacity', 03:24:36 INFO - 'tart', 03:24:36 INFO - 'tscrollx', 03:24:36 INFO - 'cart']}, 03:24:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:24:36 INFO - 'tsvgr_opacity', 03:24:36 INFO - 'tart', 03:24:36 INFO - 'tscrollx', 03:24:36 INFO - 'cart']}, 03:24:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:36 INFO - 'talos_options': ['--disable-e10s'], 03:24:36 INFO - 'tests': ['tp5o']}, 03:24:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:36 INFO - 'tests': ['tp5o']}, 03:24:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:36 INFO - 'talos_options': ['--disable-e10s', 03:24:36 INFO - '--xperf_path', 03:24:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:24:36 INFO - 'tests': ['tp5n']}, 03:24:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:24:36 INFO - 'talos_options': ['--xperf_path', 03:24:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:24:36 INFO - 'tests': ['tp5n']}}, 03:24:36 INFO - 'talos.zip': {'path': '', 03:24:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:24:36 INFO - Running post-action listener: _resource_record_post_action 03:24:36 INFO - [mozharness: 2016-09-03 10:24:36.201333Z] Finished populate-webroot step (success) 03:24:36 INFO - [mozharness: 2016-09-03 10:24:36.201422Z] Running create-virtualenv step. 03:24:36 INFO - Running pre-action listener: _resource_record_pre_action 03:24:36 INFO - Running main action method: create_virtualenv 03:24:36 INFO - Creating virtualenv /builds/slave/test/build/venv 03:24:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:24:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:24:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:24:36 INFO - Using real prefix '/usr' 03:24:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:24:36 INFO - Installing distribute.............................................................................................................................................................................................done. 03:24:38 INFO - Installing pip.................done. 03:24:38 INFO - Return code: 0 03:24:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a73b0>, '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 0x18a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24: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', 'pip>=1.5'] in /builds/slave/test/build 03:24: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 pip>=1.5 03:24:38 INFO - Using env: {'DISPLAY': ':0', 03:24:38 INFO - 'HOME': '/home/cltbld', 03:24:38 INFO - 'LANG': 'en_US.UTF-8', 03:24:38 INFO - 'LANGUAGE': 'en_US:en', 03:24:38 INFO - 'LOGNAME': 'cltbld', 03:24:38 INFO - 'MAIL': '/var/mail/cltbld', 03:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:38 INFO - 'MOZ_NO_REMOTE': '1', 03:24:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:38 INFO - 'NO_EM_RESTART': '1', 03:24:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:38 INFO - 'PWD': '/builds/slave/test', 03:24:38 INFO - 'SHELL': '/bin/bash', 03:24:38 INFO - 'SHLVL': '1', 03:24:38 INFO - 'TERM': 'linux', 03:24:38 INFO - 'TMOUT': '86400', 03:24:38 INFO - 'USER': 'cltbld', 03:24:38 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 03:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:38 INFO - '_': '/tools/buildbot/bin/python'} 03:24:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24: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 03:24:38 INFO - Cleaning up... 03:24:38 INFO - Return code: 0 03:24:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24: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 0x15e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a73b0>, '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 0x18a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24: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 03:24: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 03:24:38 INFO - Using env: {'DISPLAY': ':0', 03:24:38 INFO - 'HOME': '/home/cltbld', 03:24:38 INFO - 'LANG': 'en_US.UTF-8', 03:24:38 INFO - 'LANGUAGE': 'en_US:en', 03:24:38 INFO - 'LOGNAME': 'cltbld', 03:24:38 INFO - 'MAIL': '/var/mail/cltbld', 03:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:38 INFO - 'MOZ_NO_REMOTE': '1', 03:24:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:38 INFO - 'NO_EM_RESTART': '1', 03:24:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:38 INFO - 'PWD': '/builds/slave/test', 03:24:38 INFO - 'SHELL': '/bin/bash', 03:24:38 INFO - 'SHLVL': '1', 03:24:38 INFO - 'TERM': 'linux', 03:24:38 INFO - 'TMOUT': '86400', 03:24:38 INFO - 'USER': 'cltbld', 03:24:38 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 03:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:39 INFO - '_': '/tools/buildbot/bin/python'} 03:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:39 INFO - Downloading/unpacking psutil>=3.1.1 03:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:24:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:24:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:24:41 INFO - Installing collected packages: psutil 03:24:41 INFO - Running setup.py install for psutil 03:24:41 INFO - building 'psutil._psutil_linux' extension 03:24:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:24:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:24:41 INFO - building 'psutil._psutil_posix' extension 03:24:41 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:24:41 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:24:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:24:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:24:41 INFO - Successfully installed psutil 03:24:41 INFO - Cleaning up... 03:24:41 INFO - Return code: 0 03:24:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:41 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 0x15e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a73b0>, '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 0x18a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:24:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:24:41 INFO - Using env: {'DISPLAY': ':0', 03:24:41 INFO - 'HOME': '/home/cltbld', 03:24:41 INFO - 'LANG': 'en_US.UTF-8', 03:24:41 INFO - 'LANGUAGE': 'en_US:en', 03:24:41 INFO - 'LOGNAME': 'cltbld', 03:24:41 INFO - 'MAIL': '/var/mail/cltbld', 03:24:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:41 INFO - 'MOZ_NO_REMOTE': '1', 03:24:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:41 INFO - 'NO_EM_RESTART': '1', 03:24:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:41 INFO - 'PWD': '/builds/slave/test', 03:24:41 INFO - 'SHELL': '/bin/bash', 03:24:41 INFO - 'SHLVL': '1', 03:24:41 INFO - 'TERM': 'linux', 03:24:41 INFO - 'TMOUT': '86400', 03:24:41 INFO - 'USER': 'cltbld', 03:24:41 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 03:24:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:41 INFO - '_': '/tools/buildbot/bin/python'} 03:24:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:24: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 03:24: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 03:24: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 03:24: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 03:24: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 03:24: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 03:24:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:24:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:24:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:24:43 INFO - Installing collected packages: mozsystemmonitor 03:24:43 INFO - Running setup.py install for mozsystemmonitor 03:24:43 INFO - Successfully installed mozsystemmonitor 03:24:43 INFO - Cleaning up... 03:24:44 INFO - Return code: 0 03:24:44 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:44 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 0x15e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a73b0>, '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 0x18a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 03:24:44 INFO - Using env: {'DISPLAY': ':0', 03:24:44 INFO - 'HOME': '/home/cltbld', 03:24:44 INFO - 'LANG': 'en_US.UTF-8', 03:24:44 INFO - 'LANGUAGE': 'en_US:en', 03:24:44 INFO - 'LOGNAME': 'cltbld', 03:24:44 INFO - 'MAIL': '/var/mail/cltbld', 03:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:44 INFO - 'MOZ_NO_REMOTE': '1', 03:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:44 INFO - 'NO_EM_RESTART': '1', 03:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:44 INFO - 'PWD': '/builds/slave/test', 03:24:44 INFO - 'SHELL': '/bin/bash', 03:24:44 INFO - 'SHLVL': '1', 03:24:44 INFO - 'TERM': 'linux', 03:24:44 INFO - 'TMOUT': '86400', 03:24:44 INFO - 'USER': 'cltbld', 03:24:44 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 03:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:44 INFO - '_': '/tools/buildbot/bin/python'} 03:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:44 INFO - Downloading/unpacking jsonschema==2.5.1 03:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:24:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:24: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 03:24: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 03:24: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 03:24: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 03:24: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 03:24: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 03:24:46 INFO - Downloading functools32-3.2.3-2.tar.gz 03:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:24:46 INFO - warning: no files found matching '*.txt' 03:24:46 INFO - no previously-included directories found matching 'build' 03:24:46 INFO - no previously-included directories found matching 'dist' 03:24:46 INFO - no previously-included directories found matching '.git*' 03:24:46 INFO - Installing collected packages: jsonschema, functools32 03:24:46 INFO - Running setup.py install for functools32 03:24:46 INFO - warning: no files found matching '*.txt' 03:24:46 INFO - no previously-included directories found matching 'build' 03:24:46 INFO - no previously-included directories found matching 'dist' 03:24:46 INFO - no previously-included directories found matching '.git*' 03:24:46 INFO - Successfully installed jsonschema functools32 03:24:46 INFO - Cleaning up... 03:24:46 INFO - Return code: 0 03:24:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:46 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 0x15e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a73b0>, '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 0x18a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:24:46 INFO - Using env: {'DISPLAY': ':0', 03:24:46 INFO - 'HOME': '/home/cltbld', 03:24:46 INFO - 'LANG': 'en_US.UTF-8', 03:24:46 INFO - 'LANGUAGE': 'en_US:en', 03:24:46 INFO - 'LOGNAME': 'cltbld', 03:24:46 INFO - 'MAIL': '/var/mail/cltbld', 03:24:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:46 INFO - 'MOZ_NO_REMOTE': '1', 03:24:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:46 INFO - 'NO_EM_RESTART': '1', 03:24:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:46 INFO - 'PWD': '/builds/slave/test', 03:24:46 INFO - 'SHELL': '/bin/bash', 03:24:46 INFO - 'SHLVL': '1', 03:24:46 INFO - 'TERM': 'linux', 03:24:46 INFO - 'TMOUT': '86400', 03:24:46 INFO - 'USER': 'cltbld', 03:24:46 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 03:24:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:46 INFO - '_': '/tools/buildbot/bin/python'} 03:24:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:24:46 INFO - Cleaning up... 03:24:46 INFO - Return code: 0 03:24:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:46 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 0x15e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a73b0>, '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 0x18a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:24:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:24:46 INFO - Using env: {'DISPLAY': ':0', 03:24:46 INFO - 'HOME': '/home/cltbld', 03:24:46 INFO - 'LANG': 'en_US.UTF-8', 03:24:46 INFO - 'LANGUAGE': 'en_US:en', 03:24:46 INFO - 'LOGNAME': 'cltbld', 03:24:46 INFO - 'MAIL': '/var/mail/cltbld', 03:24:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:46 INFO - 'MOZ_NO_REMOTE': '1', 03:24:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:46 INFO - 'NO_EM_RESTART': '1', 03:24:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:46 INFO - 'PWD': '/builds/slave/test', 03:24:46 INFO - 'SHELL': '/bin/bash', 03:24:46 INFO - 'SHLVL': '1', 03:24:46 INFO - 'TERM': 'linux', 03:24:46 INFO - 'TMOUT': '86400', 03:24:46 INFO - 'USER': 'cltbld', 03:24:46 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 03:24:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:46 INFO - '_': '/tools/buildbot/bin/python'} 03:24:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:46 INFO - Downloading/unpacking blobuploader==1.2.4 03:24: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 03:24: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 03:24: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 03:24: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 03:24: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 03:24: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 03:24:48 INFO - Downloading blobuploader-1.2.4.tar.gz 03:24:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:24:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:24: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 03:24: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 03:24: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 03:24: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 03:24: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 03:24: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 03:24:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:24:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:24:49 INFO - Downloading docopt-0.6.1.tar.gz 03:24:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:24:49 INFO - Installing collected packages: blobuploader, requests, docopt 03:24:49 INFO - Running setup.py install for blobuploader 03:24:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:24:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:24:49 INFO - Running setup.py install for requests 03:24:50 INFO - Running setup.py install for docopt 03:24:50 INFO - Successfully installed blobuploader requests docopt 03:24:50 INFO - Cleaning up... 03:24:50 INFO - Return code: 0 03:24:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:50 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 0x15e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a73b0>, '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 0x18a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:24:50 INFO - Using env: {'DISPLAY': ':0', 03:24:50 INFO - 'HOME': '/home/cltbld', 03:24:50 INFO - 'LANG': 'en_US.UTF-8', 03:24:50 INFO - 'LANGUAGE': 'en_US:en', 03:24:50 INFO - 'LOGNAME': 'cltbld', 03:24:50 INFO - 'MAIL': '/var/mail/cltbld', 03:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:50 INFO - 'MOZ_NO_REMOTE': '1', 03:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:50 INFO - 'NO_EM_RESTART': '1', 03:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:50 INFO - 'PWD': '/builds/slave/test', 03:24:50 INFO - 'SHELL': '/bin/bash', 03:24:50 INFO - 'SHLVL': '1', 03:24:50 INFO - 'TERM': 'linux', 03:24:50 INFO - 'TMOUT': '86400', 03:24:50 INFO - 'USER': 'cltbld', 03:24:50 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 03:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:50 INFO - '_': '/tools/buildbot/bin/python'} 03:24:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:24:50 INFO - Running setup.py (path:/tmp/pip-6U3mHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:24:50 INFO - Running setup.py (path:/tmp/pip-hv1P5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:24:50 INFO - Running setup.py (path:/tmp/pip-fjtau4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:24:50 INFO - Running setup.py (path:/tmp/pip-M6qNmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:24:50 INFO - Running setup.py (path:/tmp/pip-KPcPH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:24:50 INFO - Running setup.py (path:/tmp/pip-qAwcKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:24:50 INFO - Running setup.py (path:/tmp/pip-8OcL7J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:24:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:24:50 INFO - Running setup.py (path:/tmp/pip-YoWQFa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:24:51 INFO - Running setup.py (path:/tmp/pip-QrXWq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:24:51 INFO - Running setup.py (path:/tmp/pip-Fe72xd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:24:51 INFO - Running setup.py (path:/tmp/pip-Zny7XW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:24:51 INFO - Running setup.py (path:/tmp/pip-3EPs8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:24:51 INFO - Running setup.py (path:/tmp/pip-vGyfpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:24:51 INFO - Running setup.py (path:/tmp/pip-z8SfGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:51 INFO - Running setup.py (path:/tmp/pip-Zm5M6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:24:51 INFO - Running setup.py (path:/tmp/pip-evBJJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:24:51 INFO - Running setup.py (path:/tmp/pip-kfXAni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:24:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:24:51 INFO - Running setup.py install for manifestparser 03:24:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:24:51 INFO - Running setup.py install for mozcrash 03:24:51 INFO - Running setup.py install for mozdebug 03:24:51 INFO - Running setup.py install for mozdevice 03:24:52 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:24:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:24:52 INFO - Running setup.py install for mozfile 03:24:52 INFO - Running setup.py install for mozhttpd 03:24:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:24:52 INFO - Running setup.py install for mozinfo 03:24:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:24:52 INFO - Running setup.py install for mozInstall 03:24:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:24:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:24:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:24:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:24:52 INFO - Running setup.py install for mozleak 03:24:52 INFO - Running setup.py install for mozlog 03:24:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:24:52 INFO - Running setup.py install for moznetwork 03:24:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:24:52 INFO - Running setup.py install for mozprocess 03:24:52 INFO - Running setup.py install for mozprofile 03:24:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:24:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:24:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:24:53 INFO - Running setup.py install for mozrunner 03:24:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:24:53 INFO - Running setup.py install for mozscreenshot 03:24:53 INFO - Running setup.py install for moztest 03:24:53 INFO - Running setup.py install for mozversion 03:24:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:24:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:24:53 INFO - Cleaning up... 03:24:53 INFO - Return code: 0 03:24:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:53 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 0x15e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a73b0>, '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 0x18a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:24:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:24:53 INFO - Using env: {'DISPLAY': ':0', 03:24:53 INFO - 'HOME': '/home/cltbld', 03:24:53 INFO - 'LANG': 'en_US.UTF-8', 03:24:53 INFO - 'LANGUAGE': 'en_US:en', 03:24:53 INFO - 'LOGNAME': 'cltbld', 03:24:53 INFO - 'MAIL': '/var/mail/cltbld', 03:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:53 INFO - 'MOZ_NO_REMOTE': '1', 03:24:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:53 INFO - 'NO_EM_RESTART': '1', 03:24:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:53 INFO - 'PWD': '/builds/slave/test', 03:24:53 INFO - 'SHELL': '/bin/bash', 03:24:53 INFO - 'SHLVL': '1', 03:24:53 INFO - 'TERM': 'linux', 03:24:53 INFO - 'TMOUT': '86400', 03:24:53 INFO - 'USER': 'cltbld', 03:24:53 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 03:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:53 INFO - '_': '/tools/buildbot/bin/python'} 03:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:24:53 INFO - Running setup.py (path:/tmp/pip-NUjnsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:24:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:24:53 INFO - Running setup.py (path:/tmp/pip-XeWvrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:24: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)) 03:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:24:53 INFO - Running setup.py (path:/tmp/pip-TkLMwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:24: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)) 03:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:24:53 INFO - Running setup.py (path:/tmp/pip-dYBesj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:24:54 INFO - Running setup.py (path:/tmp/pip-gcfQug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:24:54 INFO - Running setup.py (path:/tmp/pip-8ku3BW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:24:54 INFO - Running setup.py (path:/tmp/pip-QPtS2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:24:54 INFO - Running setup.py (path:/tmp/pip-XcwVEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:24:54 INFO - Running setup.py (path:/tmp/pip-kuejR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:24:54 INFO - Running setup.py (path:/tmp/pip-kEycbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:24:54 INFO - Running setup.py (path:/tmp/pip-C9mMws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:24:54 INFO - Running setup.py (path:/tmp/pip-vi8HrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:24:54 INFO - Running setup.py (path:/tmp/pip-Xcy7QI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:24:54 INFO - Running setup.py (path:/tmp/pip-Lk4IN7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:54 INFO - Running setup.py (path:/tmp/pip-QWcW27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:24:54 INFO - Running setup.py (path:/tmp/pip-P8K4g2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:24: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)) 03:24:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:24:54 INFO - Running setup.py (path:/tmp/pip-2vtmtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:24: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)) 03:24: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)) 03:24: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)) 03:24: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)) 03:24: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)) 03:24: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)) 03:24:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:24: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 03:24: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 03:24: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 03:24: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 03:24: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 03:24: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 03:24:56 INFO - Downloading blessings-1.6.tar.gz 03:24:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:24:56 INFO - Installing collected packages: blessings 03:24:56 INFO - Running setup.py install for blessings 03:24:56 INFO - Successfully installed blessings 03:24:56 INFO - Cleaning up... 03:24:57 INFO - Return code: 0 03:24:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:24:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:24:57 INFO - Reading from file tmpfile_stdout 03:24:57 INFO - Current package versions: 03:24:57 INFO - argparse == 1.2.1 03:24:57 INFO - blessings == 1.6 03:24:57 INFO - blobuploader == 1.2.4 03:24:57 INFO - docopt == 0.6.1 03:24:57 INFO - functools32 == 3.2.3-2 03:24:57 INFO - jsonschema == 2.5.1 03:24:57 INFO - manifestparser == 1.1 03:24:57 INFO - mozInstall == 1.12 03:24:57 INFO - mozcrash == 0.17 03:24:57 INFO - mozdebug == 0.1 03:24:57 INFO - mozdevice == 0.48 03:24:57 INFO - mozfile == 1.2 03:24:57 INFO - mozhttpd == 0.7 03:24:57 INFO - mozinfo == 0.9 03:24:57 INFO - mozleak == 0.1 03:24:57 INFO - mozlog == 3.3 03:24:57 INFO - moznetwork == 0.27 03:24:57 INFO - mozprocess == 0.23 03:24:57 INFO - mozprofile == 0.28 03:24:57 INFO - mozrunner == 6.12 03:24:57 INFO - mozscreenshot == 0.1 03:24:57 INFO - mozsystemmonitor == 0.3 03:24:57 INFO - moztest == 0.7 03:24:57 INFO - mozversion == 1.4 03:24:57 INFO - psutil == 3.1.1 03:24:57 INFO - requests == 1.2.3 03:24:57 INFO - wsgiref == 0.1.2 03:24:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:24: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')]} 03:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24: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')]} 03:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:57 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 0x15e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a73b0>, '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 0x18a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:24:57 INFO - Using env: {'DISPLAY': ':0', 03:24:57 INFO - 'HOME': '/home/cltbld', 03:24:57 INFO - 'LANG': 'en_US.UTF-8', 03:24:57 INFO - 'LANGUAGE': 'en_US:en', 03:24:57 INFO - 'LOGNAME': 'cltbld', 03:24:57 INFO - 'MAIL': '/var/mail/cltbld', 03:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:57 INFO - 'MOZ_NO_REMOTE': '1', 03:24:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:57 INFO - 'NO_EM_RESTART': '1', 03:24:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:57 INFO - 'PWD': '/builds/slave/test', 03:24:57 INFO - 'SHELL': '/bin/bash', 03:24:57 INFO - 'SHLVL': '1', 03:24:57 INFO - 'TERM': 'linux', 03:24:57 INFO - 'TMOUT': '86400', 03:24:57 INFO - 'USER': 'cltbld', 03:24:57 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 03:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:57 INFO - '_': '/tools/buildbot/bin/python'} 03:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:24:57 INFO - Cleaning up... 03:24:57 INFO - Return code: 0 03:24:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:24: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')]} 03:24:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:24:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24: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')]} 03:24:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:24:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:24:57 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 0x15e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x174b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18a73b0>, '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 0x18a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8740>, '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': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:24:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:24:57 INFO - Using env: {'DISPLAY': ':0', 03:24:57 INFO - 'HOME': '/home/cltbld', 03:24:57 INFO - 'LANG': 'en_US.UTF-8', 03:24:57 INFO - 'LANGUAGE': 'en_US:en', 03:24:57 INFO - 'LOGNAME': 'cltbld', 03:24:57 INFO - 'MAIL': '/var/mail/cltbld', 03:24:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:24:57 INFO - 'MOZ_NO_REMOTE': '1', 03:24:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:24:57 INFO - 'NO_EM_RESTART': '1', 03:24:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:24:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:24:57 INFO - 'PWD': '/builds/slave/test', 03:24:57 INFO - 'SHELL': '/bin/bash', 03:24:57 INFO - 'SHLVL': '1', 03:24:57 INFO - 'TERM': 'linux', 03:24:57 INFO - 'TMOUT': '86400', 03:24:57 INFO - 'USER': 'cltbld', 03:24:57 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 03:24:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:24:57 INFO - '_': '/tools/buildbot/bin/python'} 03:24:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:24:57 INFO - Cleaning up... 03:24:57 INFO - Return code: 0 03:24:57 INFO - Running post-action listener: _resource_record_post_action 03:24:57 INFO - Running post-action listener: _start_resource_monitoring 03:24:57 INFO - Starting resource monitoring. 03:24:57 INFO - [mozharness: 2016-09-03 10:24:57.749702Z] Finished create-virtualenv step (success) 03:24:57 INFO - [mozharness: 2016-09-03 10:24:57.750176Z] Running install step. 03:24:57 INFO - Running pre-action listener: _resource_record_pre_action 03:24:57 INFO - Running main action method: install 03:24:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:24:57 INFO - Reading from file tmpfile_stdout 03:24:57 INFO - Detecting whether we're running mozinstall >=1.0... 03:24:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:24:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:24:58 INFO - Reading from file tmpfile_stdout 03:24:58 INFO - Output received: 03:24:58 INFO - Usage: mozinstall [options] installer 03:24:58 INFO - Options: 03:24:58 INFO - -h, --help show this help message and exit 03:24:58 INFO - -d DEST, --destination=DEST 03:24:58 INFO - Directory to install application into. [default: 03:24:58 INFO - "/builds/slave/test"] 03:24:58 INFO - --app=APP Application being installed. [default: firefox] 03:24:58 INFO - mkdir: /builds/slave/test/build/application 03:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:25:14 INFO - Reading from file tmpfile_stdout 03:25:14 INFO - Output received: 03:25:14 INFO - /builds/slave/test/build/application/firefox/firefox 03:25:14 INFO - Running post-action listener: _resource_record_post_action 03:25:14 INFO - [mozharness: 2016-09-03 10:25:14.801726Z] Finished install step (success) 03:25:14 INFO - [mozharness: 2016-09-03 10:25:14.802004Z] Running run-tests step. 03:25:14 INFO - Running pre-action listener: _resource_record_pre_action 03:25:14 INFO - Running main action method: run_tests 03:25:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:25:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:25:14 INFO - Python 2.7.3 03:25:14 INFO - Return code: 0 03:25:14 INFO - grabbing minidump binary from tooltool 03:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:25:14 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 0x18a73b0>, '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 0x18a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18a8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:25:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:25:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:25:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 03:25:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:25:14 INFO - Return code: 0 03:25:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:25:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:25:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:25:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:25:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:25:14 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/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:25:14 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/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:25:14 INFO - Using env: {'DISPLAY': ':0', 03:25:14 INFO - 'HOME': '/home/cltbld', 03:25:14 INFO - 'LANG': 'en_US.UTF-8', 03:25:14 INFO - 'LANGUAGE': 'en_US:en', 03:25:14 INFO - 'LOGNAME': 'cltbld', 03:25:14 INFO - 'MAIL': '/var/mail/cltbld', 03:25:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:25:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:25:14 INFO - 'MOZ_NO_REMOTE': '1', 03:25:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:25:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:25:14 INFO - 'NO_EM_RESTART': '1', 03:25:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:25:14 INFO - 'PWD': '/builds/slave/test', 03:25:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:25:14 INFO - 'SHELL': '/bin/bash', 03:25:14 INFO - 'SHLVL': '1', 03:25:14 INFO - 'TERM': 'linux', 03:25:14 INFO - 'TMOUT': '86400', 03:25:14 INFO - 'USER': 'cltbld', 03:25:14 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684', 03:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:25:14 INFO - '_': '/tools/buildbot/bin/python'} 03:25:14 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/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:25:15 INFO - mozversion application_buildid: 20160902160050 03:25:15 INFO - mozversion application_changeset: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd 03:25:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:25:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:25:15 INFO - mozversion application_name: Firefox 03:25:15 INFO - mozversion application_remotingname: firefox-dev 03:25:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:25:15 INFO - mozversion application_vendor: Mozilla 03:25:15 INFO - mozversion application_version: 50.0a2 03:25:15 INFO - mozversion platform_buildid: 20160902160050 03:25:15 INFO - mozversion platform_changeset: d35ac86c5271f6ad22bf1f76b1bc8adb0398e7bd 03:25:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:25:15 INFO - mozversion platform_version: 50.0a2 03:25:15 INFO - using testdate: 1472898315 03:25:15 INFO - actual date: 1472898315 03:25:15 INFO - starting webserver on 'localhost:54633' 03:25:15 INFO - SUITE-START | Running 2 tests 03:25:15 INFO - TEST-START | dromaeo_css 03:25:15 INFO - operating with platform_type : linux_ 03:25:15 INFO - Initialising browser for dromaeo_css test... 03:25:15 INFO - TEST-INFO | started process 7061 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHOaSUC/profile http://localhost:54633/getInfo.html) 03:25:19 INFO - PROCESS | 7061 | __metrics Screen width/height:1600/1200 03:25:19 INFO - PROCESS | 7061 | colorDepth:24 03:25:19 INFO - PROCESS | 7061 | Browser inner width/height: 1024/697 03:25:19 INFO - PROCESS | 7061 | __metrics 03:25:19 INFO - PROCESS | 7061 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:25:19 INFO - PROCESS | 7061 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:25:21 INFO - TEST-INFO | 7061: exit 0 03:25:21 INFO - Browser initialized. 03:25:21 INFO - Running cycle 1/1 for dromaeo_css test... 03:25:21 INFO - TEST-INFO | started process 7147 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHOaSUC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:25:32 INFO - PROCESS | 7147 | RSS: Main: 145289216 03:25:32 INFO - PROCESS | 7147 | 03:26:23 INFO - PROCESS | 7147 | 1472898383740 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:28:33 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:54633/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54633/tests/dromaeo/cssquery-ext.html) 03:28:34 INFO - PROCESS | 7147 | RSS: Main: 248414208 03:28:34 INFO - PROCESS | 7147 | 03:31:36 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:54633/tests/dromaeo/cssquery-ext.html (next: http://localhost:54633/tests/dromaeo/cssquery-jquery.html) 03:31:37 INFO - PROCESS | 7147 | RSS: Main: 236032000 03:31:37 INFO - PROCESS | 7147 | 03:34:39 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:54633/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54633/tests/dromaeo/cssquery-mootools.html) 03:34:39 INFO - PROCESS | 7147 | RSS: Main: 176807936 03:34:39 INFO - PROCESS | 7147 | 03:37:41 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:54633/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54633/tests/dromaeo/cssquery-prototype.html) 03:37:42 INFO - PROCESS | 7147 | RSS: Main: 175812608 03:37:42 INFO - PROCESS | 7147 | 03:40:44 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:54633/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54633/tests/dromaeo/cssquery-yui.html) 03:40:45 INFO - PROCESS | 7147 | RSS: Main: 177741824 03:40:45 INFO - PROCESS | 7147 | 03:43:47 INFO - PROCESS | 7147 | Cycle 1(1): loaded http://localhost:54633/tests/dromaeo/cssquery-yui.html (next: http://localhost:54633/tests/dromaeo/cssquery-dojo.html) 03:43:48 INFO - PROCESS | 7147 | RSS: Main: 272162816 03:43:48 INFO - PROCESS | 7147 | 03:43:48 INFO - PROCESS | 7147 | __start_tp_report 03:43:48 INFO - PROCESS | 7147 | _x_x_mozilla_page_load 03:43:48 INFO - PROCESS | 7147 | _x_x_mozilla_page_load_details 03:43:48 INFO - PROCESS | 7147 | |i|pagename|runs| 03:43:48 INFO - PROCESS | 7147 | |0;dojo.html;3159.573457583227;3188.936221275576;3194.7603929705274;3207.6311224209207;3222.903312900613;5556.777728890845;5654.349749778777;5672.971635141824;5686.31764188297;5688.345840228375;5656.604037717361;5716.485516303532;5718.828435146942;5722.921612832437;5754.223179870716;4967.950320496785;5043.722595257259;5150.562202212816;5175.8188463403785;5241.292425522543;5111.718855462948;5167.689938603696;5170.819021334254;5171.4311425743135;5175.404828444721;5047.722375269377;5067;5073.5180157885125;5093.847184584469;5147.433782283946;5172.094883395391;5178.611604129667;5190.091733946544;5193.065248255312;5222.869428264324;4948.134076536628;5033.7734801934;5076.771545280471;5087.389513258433;5096.490350964911;5471.261379713727;5484.533814625762;5544.528715059225;5549.5837812163845;5564.221009058735;6070.700170043403;6075.568057134872;6079.656228862454;6085.532883440475;6113.174721412597;5859.882802343972;5976.551758618078;6004.519638428959;6032.366601506867;6098.26820781509;5689.886202275931;5719.28508936383;5723.427657234327;5724.341700704473;5733.483986441199;6199.597026193283;6211.09939058843;6215.595986260879;6219.40915613016;6227.06594010902;5703.258576385043;5864.794732184353;5891.734871930774;5894.8821023579285;5980.73086711099;6062.272527296753;6064.585447382513;6066.789429154379;6100.145979562864;6100.206973093558;6031.276246850406;6046.939530604725;6047.244094488189;6048.788292409745;6049.153118563405;5719.5138413235345;5876.1479585459865;5947.167396564484;5947.524198064145;6054.576179667469;4623.861284161413;4627.30590411441;4628.3288923105965;4633.93049104257;4634.745089020136;4106.3429851224255;4112.835486580503;4123.0310876943895;4133.400656904732;4146.854860079943;5586.636868603528;5705.657660540381;5745.511631511368;5759.8560035999435;5782.045962416154;6099.786507472306;6101.054336577838;6104.755809767648;6106;6130.478909292671;5813.244278243801;5815.476607105381;5822.796202132905;5823.5341172706085;5829.125631155361;5056.64603477753;5171.146760784503;5207.869803254873;5208.9739551302;5258.631895767336;6261.060840873814;6267.811965641038;6274.592151510137;6274.843128921813;6277.309495955441;6029.939700602938;6078.027515597483;6096.177016102947;6099.512039036777;6100.603460775035;6031.728572214173;6068.241469816273;6093.025115981422;6137.101203255209;6138.07928810682;5825.417458254141;5887.8233652990475;5903.498202652822;5904.557158213065;5904.793332233351;6030.849228769316;6059.575829691879;6088.391160883876;6094.421030002142;6097.634141951497;5880.970595146997;5895.9705201475435;5916.230889984275;5934.3175534814045;5935.495482884004;5667.829965101054;5915.911261330997;5931.99156143448;5942.64344139353;5945.821625351246;5768.94231057684;5789.247397838254;5818.6217895836635;5883.264591926009;5884.411558844253;5957.285125784933;5966.3735307793795;6002.579819412774;6011.909821352596;6015.639061656315;5574.247476590608;5825.825225243249;5827.679477628772;5832.708364581838;5839.766409343749 03:43:48 INFO - PROCESS | 7147 | |1;ext.html;608.8264844519313;642.8874946884296;658.7299207324995;662.8707402056598;679.1001922452751;34172.48741268883;45727.77136114319;46365.072698546006;46381;46437.7678111609;40600.79699601498;40738.37046518136;40781.59218407815;40858.38712419309;40904;8605.48367097973;9282.675106371278;9439.10328518793;9447.196988256006;9454.101860323259;8640.913590864075;8765.561721913911;8769.517676527788;11022.834657480144;11339.943300283487;11375;11403.030742386907;11463.770724585504;11476.024537914287;11481.827772583376;10753.085987691056;10805.189610779224;10817.459127043616;10842.72893177669;10848.132149428027;11068.225224234307;11109.277896936674;11130.220884538086;11144.554217831277;11146.331220126738;36562.451563226576;36897.63102368969;36941.26117477635;37069;37231.813840930896;3588.3540962626716;3905;4133.592930957005;4193.5387107418155;4519.367288579601;34506.13734656654;34940.82529587361;35000.474992875126;35234;35798.821005895064;4742.33607294979;4823.1800593899125;4824.855254342376;4844.20070688336;4933.901321973541;386897;387101.9999999972;387209;388764;391566;61287.69356153191;61415.692921536;61475.38524614786;61644.38355616386;61646.691766540884;39527.01182470461;40156.397654035216;40349.59650403517;64889.67555162194;64899.35100649027;15606.84393156054;15919.761203581955;15933.521994340188;15933.601659958595;15950.441734539236;11866.76266474683;11873.406329683481;11946.34295113777;11968.222065565711;11974.58088966882;18779.342723004902;19881.60236795256;21541.56916861654;21558.245461408776;21748.565028699653;7314.817129571803;7379.262073792685;7733.4973226740085;7735.3424958878;7847.882281765778;1017.2370721958531;1022.6267412394571;1095.9835602465969;1108.5344155454568;1146.931184128955;33848.49227261593;34113.317733645184;34228.82885585556;34271.14322141917;34643.65356346455;1158.1892675127444;1187.329159025148;1228.281455348631;1242.4719494215103;1245.725940293134;17862.910685446488;17937.641247174983;17999.470045046317;18040.819591803913;18124.284521673024;652117;653310;656095;656108;662148;62759.43101422501;63455.6827215861;63526;63531.36468635254;63542.046869296995;1318.1234479071502;1439.9064060836222;1450.2386247220084;1459.1463993563673;1478.8890833187554;33165.33669326648;34966.825165874005;35276.82361588176;35550;35702;52875.206871897724;53606.1959070622;53628.19557706559;53725.462745372046;53756.46243537514;467256;468367;468512;469577;471792;30635.387292254476;30669.8466507666;30916.690833091383;31376;31568.684313157493;51300.74349628377;51588.22617660813;52002.47997519976;52041.7397913008;52165.47834521606;42688.10225173449;43679;44386.556134438244;45297;45534.316985244586;36764.63235367612;38408.423873642474;43360.349594756735;43511.78244108759;45233.32150017818;42962.140757184054;43047.56952430561;43687.78156109199;43968.12063758643;44326;37775.62224377721;39176.60823391729;39981.600183998955;40136;40538;29859;29882.252943675714;30148.84925575446;30230;30327.393452131277 03:43:48 INFO - PROCESS | 7147 | |2;jquery.html;386.7505458978957;422.69777109366794;425.97444153350796;432.2112145334765;441.97348159110453;16127;21258.57482850344;21271.468213294676;21536.784632153693;21578.352649420503;20169.294074707388;20455.079521421536;20482.2831200908;20876;20892.268770593033;4137.400076988834;5899.970500147515;5949.375315591855;5997.490213331872;6150.784722534712;6079.726412311435;6153.538484613649;6199;6220.973539365999;6246.031865060922;6115.327313995457;6144.41628045335;6230.657313847737;6237.3450787667325;6257.62454252744;6017.939820601781;6234.968825155868;6244.157038799772;6255.562110652256;6265.5614107012525;6079.300880398722;6126.693665316761;6134.723937422827;6134.846628834271;6135.2024236849375;14424.423023079064;14652.41390344385;22944.770552294598;25106.372340691447;25658.48683026348;2410.433548116191;2484.1305543059966;2513.6229565565313;2582.2382397192873;2768.5708715149185;10764.515596798163;15013;16054.75917861233;17157.313707451685;17963.550911227194;2094.3402828109097;2118;2144.0994782191515;2236.250855963238;2275.7496675365696;97768.0223197766;101559.49220253926;103081.48459257731;104564.95435045628;105088;18278.81721182771;18352.54118647018;18570.050144734607;18669.253229870923;18992.43022709321;33549.49675754866;33866;33881.83059084738;34742.82628586841;34787.826060869535;9833;9992.10071093605;10036.598536058476;10093.899061009295;10155.492225388849;8192.30365418946;8277.420580559303;8278.503289802631;8397.70608028716;8428.78928026792;7581.886271705929;9112.362134650512;9321.394109382869;9357.766055848659;9582.760430989281;3587.461880717913;3596.478510615946;3622.5109610202812;3632.6185750496347;3783.281176576442;984.3503287829998;994.5275993902839;1001.9699409017741;1057.836379982029;1087.0596933652491;20826.47933801637;20967.26614568483;21274.043267885016;21352.679709804364;21355.57288854214;966.9033096690275;985.7387792235063;1009.7828966772179;1046.733083063829;1084.2897901874285;10688.89311106879;11851.170418070824;12083.214591051554;12112.878871211351;12186.086043546593;572739;573678;573715;575166;591557;87191;90389.54805226064;91057.54471227733;92879.53560232156;93023;1031.5770534080991;1043.3062013760764;1052.400131924811;1064.7284942339654;1071.2822408985999;18162.818371816113;18164.72752908681;18322.26710931548;18755.93305021234;19204.615907682048;44393;44566;44682;44784.10431791281;45571.31643025291;61346.69326653517;62584.37415625968;62747.37252627415;63080;63196.684016579624;9452.790985735526;9550.092741189575;9582.808343833223;9593.42439453607;9607.90392096098;8248.711295104642;13334.933325333312;13574.93212533931;14000.649983750487;14539.636509087357;12273.140880138304;12478;12506.624801255977;12659.746805064031;12739.108262421541;11578.421078946187;11869.5845645402;11903.821442678194;11927.76144477123;12002.339871306813;11413.429328533706;12598.811017834923;12673.366331683195;12935.870641293843;12949.54676586315;9204.677836275698;9260.675876344565;9383.812323753351;9387.483688397204;9425.05749425052;10685.51915163804;12301.384930753247;12319;12345.197562158432;12638.936805315914 03:43:48 INFO - PROCESS | 7147 | |3;mootools.html;1427.8286605607325;1498.1086253679057;1688.1812321024302;1881.1346780480978;1896.2225487550106;9383.343165978384;10055.095041446277;10063.698089057321;10069.798604027925;10081.647142350019;8510.404271700983;9145.634174633007;9307.441553506795;9362;9405.247580193585;2555.2589748972823;2583.547879121156;2583.586626139818;2590.8575028373434;2591.3003489058037;2503.5493611149986;2563.3207200091897;2572.588385858263;2573.7812285955624;2583.147561304765;2553.885075171622;2582.535143674138;2590.870456477178;2592.546304396723;2592.909248176314;2467.568175569277;2478.950420991579;2484.453420247534;2500.8874600643016;2509.2597683683357;2410.746871578494;2418.6130219165025;2420.636904464327;2433.8783060847063;2475.6410320503605;9746.707598772047;9755.170810481117;9800.460974646392;9817.950910245403;9853.211743060538;5762.827115186551;5955.464008239279;5956.16613674086;5959.553033522459;5963;3659.981700091509;3733.757305775116;3772.5472943246714;3791.9241615167816;3865.188310454808;1187.6140254417262;1213.8725433829409;1243.3286025546195;1247.4261839553817;1251.5744646820124;13215;13469.797953030713;13490.932545337211;13880.514182003682;14102.71794564103;5029.84910452687;5138.563222126087;5171.095058364846;5188.143956247252;5288.735563221823;10972;11003.504842282116;11008.559657613605;11014.06380457654;11045.558177672803;5865.912011319833;5869.589128761031;5874.030784920526;5876;5876.382979787061;4605.378273933044;5262.71055091966;5327.946720532745;5440.483154100354;5459.317585301837;4202.873913782591;4283.828646854154;4336.219480493478;4348.043430445297;4371.409859668967;1269.6254604891578;1273.4969686973593;1280.9487620495263;1295.533607901155;1306.2293246984325;878.5914549734404;1082.4966390628394;1112.8386383974441;1122.4163435013747;1123.9100871930389;6561.475081993429;6833.829154271084;6861;6862.954659561515;6939.271376505495;500.8472415913109;501.7541404711665;510.61448606302747;513.5839969624616;513.7354262554787;4612.939024024426;4760.7619619018915;4769.689970151929;4835.20219163834;4869.269609558594;12097.57658481949;12268.631941041784;12365.196262242926;12372.257664540157;12421.875781242252;11639.243449175776;12115.39423028845;12123.575674851338;12457;12500.437480313407;8756.693515726918;8917.42036767595;8937.419067760575;8942.552872356357;8942.776430589287;6959.130108736408;6964.338387853146;7138.678759455733;7267.890981635385;7340.743073992385;11624.476898539755;11681.707957301136;11791.056715462571;11833.053355731348;11881.405929703307;11082.224244303145;11350.602728904449;11398.886011139783;11550.075993920635;11733.530658773554;8055.234752698484;8230.382721295808;8302.128276531119;8429.536375499407;8702.82594348122;9460.479673618018;9524.95237523808;9536.57085431171;9542.284328675238;9553.426794392359;8656.913430865863;8693.261072808882;8728;8739.737807865775;8772.473651580925;8270.503769773833;8290.295324897452;8504.82969493008;8534;8618.095100014409;8157.8368432632205;8179.813926980673;8221.630026648934;8222.547759873265;8224.547649879314;6002.129691194665;6024.608400454132;6159.29168145669;6166.136740856194;6238.937610624017;5809.738561764815;5871.209281169285;5932.584719069624;6089.969550152221;6109.358517355614 03:43:48 INFO - PROCESS | 7147 | |4;prototype.html;358.7022771099987;364.456959130895;366.91927775889314;367.14272174472603;368.3369934118586;5540.944590554093;5641.771500257401;5649.745761440735;5651.717414129293;5652.06740887753;5421.457854214576;5545.584081193906;5595;5604.206653870753;5615.511889349325;4355;4370.169667763115;4395.637352420744;4410.338449232609;4425.225585522537;4345.326474396474;4350.934735978963;4360.56394360563;4360.956390436103;4368.759718215497;4343.522212556616;4345.521992580813;4356.128774245148;4371.956280437187;4405.185040767468;4237.470316210473;4358.716683415583;4381.189479946188;4388.670849686287;4399.5160532341415;3926.391409331558;3932.842686292545;3939.212157568498;3946.9013274668077;3951.6641085507767;5435.1847222916485;5460.563154947635;5494.972525137348;5496.4716230534;5505.532029777473;4946.526736631684;4991.550760431579;5027.321311622921;5051.520105589963;5074.264231686385;5729.799457019026;5747.3677895431465;5757.856053598652;5767.221425107598;5774.711264436762;4321.135772845443;4334.891627709301;4337.761423121727;4345.782710864476;4363.52001279859;5710.257666503369;5862.648241105548;5879.942429936409;6106.641347259034;6110.8834494408175;4341.37050127736;4349.695521313478;4350.412694286294;4386.692931494765;4409.404730361424;4469.374287599739;4477.104579084196;4478.417805685305;4489.057297967398;4493.303537951622;4803.567678908915;4904.337914381585;4935.457099719028;5015.3229314042865;5023.321851550067;5819.709014549256;5823.417658234142;5833.125031245348;5834.27071616048;5888.646681199141;1545.5749668841158;1561.773542836305;1562.7577725452345;1565.1548163991317;1565.8590726834639;987.683941138843;1045;1055.904968552834;1057.8519007338978;1061.3578784835063;5666.546676265889;5667.688277144796;5676.716164191774;5705.4009329019855;5707.885842283131;5916.319623243296;6002.489788368038;6029.161105862754;6054.606450580698;6118.632882027093;5649.378568357477;5658.726786473011;5667.603267771216;5683.5737319700365;5742.885142297214;3141.413586139031;3176.936461270762;3215.7909735867092;3220.5652236947712;3222.7099561039618;6030.698465076729;6109.816705498843;6127.295361033449;6164.229471316085;6260.530460215411;6027.397260273938;6034.48706859913;6041.6375017499095;6056.454919057218;6088.939110608837;6005.048359283251;6096.78661246872;6181.196444462281;6188.762247550508;6210.254769427697;5887.3523912370465;5913;5920.921121017133;5936.079907614327;5955.344912059757;5646.616578938187;5865.562937080272;5887.764489420375;5889.676067816311;5896.410358964069;5705.600607957569;6196.47329976939;6201.379862013762;6214.8135555933395;6216.968915155395;4258.5741425858405;4272.632757517565;4285.46431696038;4292.141571685738;4299.9355009674255;5829.183914251923;6091.269047714302;6108.602940808833;6110.7861224856915;6139.72371243304;5831.125331200354;5832.941670583409;5884.264466941632;5885.9405307043435;5915.852103697442;5840.707964601838;5926.7925622603;5943.227380440688;5949.286085669747;5956.5532585055425;5605.215269862141;5846.327672317738;5861.501772349227;5871.4715675589405;5916.787058653052;5907.763689452374;5921.230240068763;5935.910961335497;5938.654231578416;5950.821475355746;5674.184261036594;5818.534517238695;5828.213191219301;5862.530997520103;5886.499647530008 03:43:48 INFO - PROCESS | 7147 | |5;yui.html;796.8207153390488;798.3174385900055;806.6128258435947;831.0775039705921;905.3798148268435;15285;16127.516174514754;16246.187690615481;16257.918710406446;16309.75535366969;16041.438549650735;16286.104264265461;16306.592335191626;16311.265993030313;16448.095354755485;876.1063715010686;895.928325733943;896.368060517337;900.7928176519405;900.9234215091725;898.8112496375736;899.2221728205121;901.1889299630352;901.7430032440756;902.2826852652157;944.7496413450441;945.3146468810093;945.7824700318931;945.9952700236489;946.0633972367341;942.2038377570941;944.3106532231475;945.135201290818;945.3477100800426;945.6973768394116;690.440742998173;691.9515633905629;695.2873304862557;695.3185877839695;695.6035060015793;17269.222884970208;17303;17458.563535911577;17468.737968930476;17488.91255543727;16271.755923661154;16536.917315413466;16722.331106755715;17031.57421064471;17260.39588614405;13079.28063956482;13322.000849936197;13389.66525836862;13395.86604133956;13563.45746170152;4082.6529744971713;4115.73247738896;4140.3168477201525;4147.274227010292;4195.643370313527;87965;88907;89404;90788.0066596337;91076;11900.821487677691;11910.46402911871;12092.274463532216;12447.564335248404;12465.93767031159;23241;23592.05631774744;23662.881685591463;23729.644055339184;23737.05051797909;9305.441673499612;9386.85919711205;9500.144986951207;9553.283503737108;9591.808163836684;4759.690620109682;4781.6891902026255;4789.688670236424;4943.036107958914;5024.824131155392;3827.8660246891227;3976.642102210757;4003.3594624859884;4005.9799701001893;4007.979960100239;726.4370113162341;737.9372753315921;750.6096829648552;755.9433042521833;761.885717142422;754.7547047209679;769.6959700918106;808.4825711544617;836.1471299274828;838.8783626374142;10820.891791082146;10830.296030757976;10850.837237441445;11638.825417618742;12013;784.7998760316196;809.2312303311877;819.1644522586928;825.938054645904;849.0490650471388;5079.742446755819;5373.3551973762615;5390.19147127934;5471.151971444432;5521.889562208733;107803;121138;123347;126302;127313;103344;106248.46875765572;107057;107291.46354268178;110511;90919;91389;93319;93764;94670.5266473677;9854.802903941738;10206.13247873939;10310.742231444274;10337.121344685786;10341.793164136525;53647.463525365805;54207;54343.72828135834;54594;56755.54849093041;74095.62952185204;75298.24701752912;78202.6089869547;78654;78850.6057469709;12907.483700651868;13046.152000119962;13096.476140954635;13169.473221071434;13234.338283086;13768.311584420939;13848.238346891017;13908.86091139116;14325.426982920566;14688.853111469176;13478.46086156582;13955.790663139858;14117.647058823612;14260.358283877044;14345.426182952982;12597.496100155891;13231.470741170246;13362.997775166707;13515.872150103694;14416.846652268003;13225;13702.10936289138;13732.725345493234;13996.16023038621;14339.713205736036;11000.284981476498;11106.722331941766;11613.30320180792;11745.882541174478;11939.820902686293;11374.601888933848;11587;11626.302421854378;11849.52601895949;11870.169088164092 03:43:48 INFO - PROCESS | 7147 | __end_tp_report 03:43:48 INFO - PROCESS | 7147 | __start_cc_report 03:43:48 INFO - PROCESS | 7147 | _x_x_mozilla_cycle_collect,966 03:43:48 INFO - PROCESS | 7147 | __end_cc_report 03:43:48 INFO - PROCESS | 7147 | __startTimestamp1472899428140__endTimestamp 03:43:48 INFO - PROCESS | 7147 | 03:43:48 INFO - PROCESS | 7147 | ------- Summary: start ------- 03:43:48 INFO - PROCESS | 7147 | Number of tests: 6 03:43:48 INFO - PROCESS | 7147 | 03:43:48 INFO - PROCESS | 7147 | [#0] dojo.html Cycles:165 Average:5605.26 Median:5825.83 stddev:646.12 (11.1%) stddev-sans-first:618.96 03:43:48 INFO - PROCESS | 7147 | Values: 3159.6 3188.9 3194.8 3207.6 3222.9 5556.8 5654.3 5673.0 5686.3 5688.3 5656.6 5716.5 5718.8 5722.9 5754.2 4968.0 5043.7 5150.6 5175.8 5241.3 5111.7 5167.7 5170.8 5171.4 5175.4 5047.7 5067.0 5073.5 5093.8 5147.4 5172.1 5178.6 5190.1 5193.1 5222.9 4948.1 5033.8 5076.8 5087.4 5096.5 5471.3 5484.5 5544.5 5549.6 5564.2 6070.7 6075.6 6079.7 6085.5 6113.2 5859.9 5976.6 6004.5 6032.4 6098.3 5689.9 5719.3 5723.4 5724.3 5733.5 6199.6 6211.1 6215.6 6219.4 6227.1 5703.3 5864.8 5891.7 5894.9 5980.7 6062.3 6064.6 6066.8 6100.1 6100.2 6031.3 6046.9 6047.2 6048.8 6049.2 5719.5 5876.1 5947.2 5947.5 6054.6 4623.9 4627.3 4628.3 4633.9 4634.7 4106.3 4112.8 4123.0 4133.4 4146.9 5586.6 5705.7 5745.5 5759.9 5782.0 6099.8 6101.1 6104.8 6106.0 6130.5 5813.2 5815.5 5822.8 5823.5 5829.1 5056.6 5171.1 5207.9 5209.0 5258.6 6261.1 6267.8 6274.6 6274.8 6277.3 6029.9 6078.0 6096.2 6099.5 6100.6 6031.7 6068.2 6093.0 6137.1 6138.1 5825.4 5887.8 5903.5 5904.6 5904.8 6030.8 6059.6 6088.4 6094.4 6097.6 5881.0 5896.0 5916.2 5934.3 5935.5 5667.8 5915.9 5932.0 5942.6 5945.8 5768.9 5789.2 5818.6 5883.3 5884.4 5957.3 5966.4 6002.6 6011.9 6015.6 5574.2 5825.8 5827.7 5832.7 5839.8 03:43:48 INFO - PROCESS | 7147 | 03:43:48 INFO - PROCESS | 7147 | [#1] ext.html Cycles:180 Average:66361.45 Median:196334.50 stddev:137618.59 (70.1%) stddev-sans-first:137916.10 03:43:48 INFO - PROCESS | 7147 | Values: 608.8 642.9 658.7 662.9 679.1 34172.5 45727.8 46365.1 46381.0 46437.8 40600.8 40738.4 40781.6 40858.4 40904.0 8605.5 9282.7 9439.1 9447.2 9454.1 8640.9 8765.6 8769.5 11022.8 11339.9 11375.0 11403.0 11463.8 11476.0 11481.8 10753.1 10805.2 10817.5 10842.7 10848.1 11068.2 11109.3 11130.2 11144.6 11146.3 36562.5 36897.6 36941.3 37069.0 37231.8 3588.4 3905.0 4133.6 4193.5 4519.4 34506.1 34940.8 35000.5 35234.0 35798.8 4742.3 4823.2 4824.9 4844.2 4933.9 386897.0 387102.0 387209.0 388764.0 391566.0 61287.7 61415.7 61475.4 61644.4 61646.7 39527.0 40156.4 40349.6 64889.7 64899.4 15606.8 15919.8 15933.5 15933.6 15950.4 11866.8 11873.4 11946.3 11968.2 11974.6 18779.3 19881.6 21541.6 21558.2 21748.6 7314.8 7379.3 7733.5 7735.3 7847.9 1017.2 1022.6 1096.0 1108.5 1146.9 33848.5 34113.3 34228.8 34271.1 34643.7 1158.2 1187.3 1228.3 1242.5 1245.7 17862.9 17937.6 17999.5 18040.8 18124.3 652117.0 653310.0 656095.0 656108.0 662148.0 62759.4 63455.7 63526.0 63531.4 63542.0 1318.1 1439.9 1450.2 1459.1 1478.9 33165.3 34966.8 35276.8 35550.0 35702.0 52875.2 53606.2 53628.2 53725.5 53756.5 467256.0 468367.0 468512.0 469577.0 471792.0 30635.4 30669.8 30916.7 31376.0 31568.7 51300.7 51588.2 52002.5 52041.7 52165.5 42688.1 43679.0 44386.6 45297.0 45534.3 36764.6 38408.4 43360.3 43511.8 45233.3 42962.1 43047.6 43687.8 43968.1 44326.0 37775.6 39176.6 39981.6 40136.0 40538.0 29859.0 29882.3 30148.8 30230.0 30327.4 03:43:48 INFO - PROCESS | 7147 | 03:43:48 INFO - PROCESS | 7147 | [#2] jquery.html Cycles:180 Average:33591.53 Median:2675.40 stddev:94965.59 (3549.6%) stddev-sans-first:95199.26 03:43:48 INFO - PROCESS | 7147 | Values: 386.8 422.7 426.0 432.2 442.0 16127.0 21258.6 21271.5 21536.8 21578.4 20169.3 20455.1 20482.3 20876.0 20892.3 4137.4 5900.0 5949.4 5997.5 6150.8 6079.7 6153.5 6199.0 6221.0 6246.0 6115.3 6144.4 6230.7 6237.3 6257.6 6017.9 6235.0 6244.2 6255.6 6265.6 6079.3 6126.7 6134.7 6134.8 6135.2 14424.4 14652.4 22944.8 25106.4 25658.5 2410.4 2484.1 2513.6 2582.2 2768.6 10764.5 15013.0 16054.8 17157.3 17963.6 2094.3 2118.0 2144.1 2236.3 2275.7 97768.0 101559.5 103081.5 104565.0 105088.0 18278.8 18352.5 18570.1 18669.3 18992.4 33549.5 33866.0 33881.8 34742.8 34787.8 9833.0 9992.1 10036.6 10093.9 10155.5 8192.3 8277.4 8278.5 8397.7 8428.8 7581.9 9112.4 9321.4 9357.8 9582.8 3587.5 3596.5 3622.5 3632.6 3783.3 984.4 994.5 1002.0 1057.8 1087.1 20826.5 20967.3 21274.0 21352.7 21355.6 966.9 985.7 1009.8 1046.7 1084.3 10688.9 11851.2 12083.2 12112.9 12186.1 572739.0 573678.0 573715.0 575166.0 591557.0 87191.0 90389.5 91057.5 92879.5 93023.0 1031.6 1043.3 1052.4 1064.7 1071.3 18162.8 18164.7 18322.3 18755.9 19204.6 44393.0 44566.0 44682.0 44784.1 45571.3 61346.7 62584.4 62747.4 63080.0 63196.7 9452.8 9550.1 9582.8 9593.4 9607.9 8248.7 13334.9 13574.9 14000.6 14539.6 12273.1 12478.0 12506.6 12659.7 12739.1 11578.4 11869.6 11903.8 11927.8 12002.3 11413.4 12598.8 12673.4 12935.9 12949.5 9204.7 9260.7 9383.8 9387.5 9425.1 10685.5 12301.4 12319.0 12345.2 12638.9 03:43:48 INFO - PROCESS | 7147 | 03:43:48 INFO - PROCESS | 7147 | [#3] mootools.html Cycles:180 Average:6465.29 Median:4765.23 stddev:3707.56 (77.8%) stddev-sans-first:3698.63 03:43:48 INFO - PROCESS | 7147 | Values: 1427.8 1498.1 1688.2 1881.1 1896.2 9383.3 10055.1 10063.7 10069.8 10081.6 8510.4 9145.6 9307.4 9362.0 9405.2 2555.3 2583.5 2583.6 2590.9 2591.3 2503.5 2563.3 2572.6 2573.8 2583.1 2553.9 2582.5 2590.9 2592.5 2592.9 2467.6 2479.0 2484.5 2500.9 2509.3 2410.7 2418.6 2420.6 2433.9 2475.6 9746.7 9755.2 9800.5 9818.0 9853.2 5762.8 5955.5 5956.2 5959.6 5963.0 3660.0 3733.8 3772.5 3791.9 3865.2 1187.6 1213.9 1243.3 1247.4 1251.6 13215.0 13469.8 13490.9 13880.5 14102.7 5029.8 5138.6 5171.1 5188.1 5288.7 10972.0 11003.5 11008.6 11014.1 11045.6 5865.9 5869.6 5874.0 5876.0 5876.4 4605.4 5262.7 5327.9 5440.5 5459.3 4202.9 4283.8 4336.2 4348.0 4371.4 1269.6 1273.5 1280.9 1295.5 1306.2 878.6 1082.5 1112.8 1122.4 1123.9 6561.5 6833.8 6861.0 6863.0 6939.3 500.8 501.8 510.6 513.6 513.7 4612.9 4760.8 4769.7 4835.2 4869.3 12097.6 12268.6 12365.2 12372.3 12421.9 11639.2 12115.4 12123.6 12457.0 12500.4 8756.7 8917.4 8937.4 8942.6 8942.8 6959.1 6964.3 7138.7 7267.9 7340.7 11624.5 11681.7 11791.1 11833.1 11881.4 11082.2 11350.6 11398.9 11550.1 11733.5 8055.2 8230.4 8302.1 8429.5 8702.8 9460.5 9525.0 9536.6 9542.3 9553.4 8656.9 8693.3 8728.0 8739.7 8772.5 8270.5 8290.3 8504.8 8534.0 8618.1 8157.8 8179.8 8221.6 8222.5 8224.5 6002.1 6024.6 6159.3 6166.1 6238.9 5809.7 5871.2 5932.6 6090.0 6109.4 03:43:48 INFO - PROCESS | 7147 | 03:43:48 INFO - PROCESS | 7147 | [#4] prototype.html Cycles:180 Average:4949.47 Median:5651.89 stddev:1429.03 (25.3%) stddev-sans-first:1390.88 03:43:48 INFO - PROCESS | 7147 | Values: 358.7 364.5 366.9 367.1 368.3 5540.9 5641.8 5649.7 5651.7 5652.1 5421.5 5545.6 5595.0 5604.2 5615.5 4355.0 4370.2 4395.6 4410.3 4425.2 4345.3 4350.9 4360.6 4361.0 4368.8 4343.5 4345.5 4356.1 4372.0 4405.2 4237.5 4358.7 4381.2 4388.7 4399.5 3926.4 3932.8 3939.2 3946.9 3951.7 5435.2 5460.6 5495.0 5496.5 5505.5 4946.5 4991.6 5027.3 5051.5 5074.3 5729.8 5747.4 5757.9 5767.2 5774.7 4321.1 4334.9 4337.8 4345.8 4363.5 5710.3 5862.6 5879.9 6106.6 6110.9 4341.4 4349.7 4350.4 4386.7 4409.4 4469.4 4477.1 4478.4 4489.1 4493.3 4803.6 4904.3 4935.5 5015.3 5023.3 5819.7 5823.4 5833.1 5834.3 5888.6 1545.6 1561.8 1562.8 1565.2 1565.9 987.7 1045.0 1055.9 1057.9 1061.4 5666.5 5667.7 5676.7 5705.4 5707.9 5916.3 6002.5 6029.2 6054.6 6118.6 5649.4 5658.7 5667.6 5683.6 5742.9 3141.4 3176.9 3215.8 3220.6 3222.7 6030.7 6109.8 6127.3 6164.2 6260.5 6027.4 6034.5 6041.6 6056.5 6088.9 6005.0 6096.8 6181.2 6188.8 6210.3 5887.4 5913.0 5920.9 5936.1 5955.3 5646.6 5865.6 5887.8 5889.7 5896.4 5705.6 6196.5 6201.4 6214.8 6217.0 4258.6 4272.6 4285.5 4292.1 4299.9 5829.2 6091.3 6108.6 6110.8 6139.7 5831.1 5832.9 5884.3 5885.9 5915.9 5840.7 5926.8 5943.2 5949.3 5956.6 5605.2 5846.3 5861.5 5871.5 5916.8 5907.8 5921.2 5935.9 5938.7 5950.8 5674.2 5818.5 5828.2 5862.5 5886.5 03:43:48 INFO - PROCESS | 7147 | 03:43:48 INFO - PROCESS | 7147 | [#5] yui.html Cycles:180 Average:22395.51 Median:3990.00 stddev:32277.53 (809.0%) stddev-sans-first:32327.33 03:43:48 INFO - PROCESS | 7147 | Values: 796.8 798.3 806.6 831.1 905.4 15285.0 16127.5 16246.2 16257.9 16309.8 16041.4 16286.1 16306.6 16311.3 16448.1 876.1 895.9 896.4 900.8 900.9 898.8 899.2 901.2 901.7 902.3 944.7 945.3 945.8 946.0 946.1 942.2 944.3 945.1 945.3 945.7 690.4 692.0 695.3 695.3 695.6 17269.2 17303.0 17458.6 17468.7 17488.9 16271.8 16536.9 16722.3 17031.6 17260.4 13079.3 13322.0 13389.7 13395.9 13563.5 4082.7 4115.7 4140.3 4147.3 4195.6 87965.0 88907.0 89404.0 90788.0 91076.0 11900.8 11910.5 12092.3 12447.6 12465.9 23241.0 23592.1 23662.9 23729.6 23737.1 9305.4 9386.9 9500.1 9553.3 9591.8 4759.7 4781.7 4789.7 4943.0 5024.8 3827.9 3976.6 4003.4 4006.0 4008.0 726.4 737.9 750.6 755.9 761.9 754.8 769.7 808.5 836.1 838.9 10820.9 10830.3 10850.8 11638.8 12013.0 784.8 809.2 819.2 825.9 849.0 5079.7 5373.4 5390.2 5471.2 5521.9 107803.0 121138.0 123347.0 126302.0 127313.0 103344.0 106248.5 107057.0 107291.5 110511.0 90919.0 91389.0 93319.0 93764.0 94670.5 9854.8 10206.1 10310.7 10337.1 10341.8 53647.5 54207.0 54343.7 54594.0 56755.5 74095.6 75298.2 78202.6 78654.0 78850.6 12907.5 13046.2 13096.5 13169.5 13234.3 13768.3 13848.2 13908.9 14325.4 14688.9 13478.5 13955.8 14117.6 14260.4 14345.4 12597.5 13231.5 13363.0 13515.9 14416.8 13225.0 13702.1 13732.7 13996.2 14339.7 11000.3 11106.7 11613.3 11745.9 11939.8 11374.6 11587.0 11626.3 11849.5 11870.2 03:43:48 INFO - PROCESS | 7147 | -------- Summary: end -------- 03:43:48 INFO - PROCESS | 7147 | 03:43:48 INFO - TEST-INFO | 7147: exit 0 03:43:48 INFO - TEST-OK | dromaeo_css | took 1113830ms 03:43:48 INFO - TEST-START | kraken 03:43:48 INFO - operating with platform_type : linux_ 03:43:48 INFO - Initialising browser for kraken test... 03:43:48 INFO - TEST-INFO | started process 7353 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxYP0XM/profile http://localhost:54633/getInfo.html) 03:43:52 INFO - PROCESS | 7353 | __metrics Screen width/height:1600/1200 03:43:52 INFO - PROCESS | 7353 | colorDepth:24 03:43:52 INFO - PROCESS | 7353 | Browser inner width/height: 1024/697 03:43:52 INFO - PROCESS | 7353 | __metrics 03:43:53 INFO - PROCESS | 7353 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:43:53 INFO - PROCESS | 7353 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:43:54 INFO - TEST-INFO | 7353: exit 0 03:43:54 INFO - Browser initialized. 03:43:54 INFO - Running cycle 1/1 for kraken test... 03:43:54 INFO - TEST-INFO | started process 7444 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxYP0XM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:44:06 INFO - PROCESS | 7444 | RSS: Main: 145240064 03:44:06 INFO - PROCESS | 7444 | 03:44:09 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54633/tests/kraken/driver.html?testName=audio-beat-detection) 03:44:09 INFO - PROCESS | 7444 | RSS: Main: 191111168 03:44:09 INFO - PROCESS | 7444 | 03:44:12 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54633/tests/kraken/driver.html?testName=audio-dft) 03:44:13 INFO - PROCESS | 7444 | RSS: Main: 181481472 03:44:13 INFO - PROCESS | 7444 | 03:44:15 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54633/tests/kraken/driver.html?testName=audio-fft) 03:44:16 INFO - PROCESS | 7444 | RSS: Main: 269582336 03:44:16 INFO - PROCESS | 7444 | 03:44:18 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54633/tests/kraken/driver.html?testName=audio-oscillator) 03:44:19 INFO - PROCESS | 7444 | RSS: Main: 175521792 03:44:19 INFO - PROCESS | 7444 | 03:44:21 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54633/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:44:21 INFO - PROCESS | 7444 | RSS: Main: 176570368 03:44:21 INFO - PROCESS | 7444 | 03:44:24 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54633/tests/kraken/driver.html?testName=imaging-darkroom) 03:44:25 INFO - PROCESS | 7444 | RSS: Main: 212762624 03:44:25 INFO - PROCESS | 7444 | 03:44:28 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54633/tests/kraken/driver.html?testName=imaging-desaturate) 03:44:29 INFO - PROCESS | 7444 | RSS: Main: 212013056 03:44:29 INFO - PROCESS | 7444 | 03:44:32 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54633/tests/kraken/driver.html?testName=json-parse-financial) 03:44:32 INFO - PROCESS | 7444 | RSS: Main: 211673088 03:44:32 INFO - PROCESS | 7444 | 03:44:34 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54633/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:44:35 INFO - PROCESS | 7444 | RSS: Main: 176001024 03:44:35 INFO - PROCESS | 7444 | 03:44:36 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54633/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:44:37 INFO - PROCESS | 7444 | RSS: Main: 182853632 03:44:37 INFO - PROCESS | 7444 | 03:44:39 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54633/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:44:39 INFO - PROCESS | 7444 | RSS: Main: 186290176 03:44:39 INFO - PROCESS | 7444 | 03:44:42 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54633/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:44:42 INFO - PROCESS | 7444 | RSS: Main: 180527104 03:44:42 INFO - PROCESS | 7444 | 03:44:45 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54633/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:44:46 INFO - PROCESS | 7444 | RSS: Main: 184193024 03:44:46 INFO - PROCESS | 7444 | 03:44:47 INFO - PROCESS | 7444 | Cycle 1(1): loaded http://localhost:54633/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54633/tests/kraken/driver.html?testName=ai-astar) 03:44:48 INFO - PROCESS | 7444 | RSS: Main: 236191744 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | __start_tp_report 03:44:48 INFO - PROCESS | 7444 | _x_x_mozilla_page_load 03:44:48 INFO - PROCESS | 7444 | _x_x_mozilla_page_load_details 03:44:48 INFO - PROCESS | 7444 | |i|pagename|runs| 03:44:48 INFO - PROCESS | 7444 | |0;ai-astar;100;109;109;109;110;109;109;109;109;109 03:44:48 INFO - PROCESS | 7444 | |1;audio-beat-detection;153;116;119;175;116;119;148;115;167;117 03:44:48 INFO - PROCESS | 7444 | |2;audio-dft;159;161;161;160;160;161;160;164;163;162 03:44:48 INFO - PROCESS | 7444 | |3;audio-fft;76;75;74;107;76;75;74;75;100;75 03:44:48 INFO - PROCESS | 7444 | |4;audio-oscillator;81;82;125;82;82;83;119;82;83;132 03:44:48 INFO - PROCESS | 7444 | |5;imaging-gaussian-blur;118;117;117;117;117;116;117;116;116;117 03:44:48 INFO - PROCESS | 7444 | |6;imaging-darkroom;150;150;150;150;151;149;149;150;151;152 03:44:48 INFO - PROCESS | 7444 | |7;imaging-desaturate;95;96;95;95;95;95;95;95;94;94 03:44:48 INFO - PROCESS | 7444 | |8;json-parse-financial;50;49;50;49;49;50;50;49;50;50 03:44:48 INFO - PROCESS | 7444 | |9;json-stringify-tinderbox;59;60;58;59;58;58;58;59;58;59 03:44:48 INFO - PROCESS | 7444 | |10;stanford-crypto-aes;73;81;72;73;73;80;72;72;71;80 03:44:48 INFO - PROCESS | 7444 | |11;stanford-crypto-ccm;129;115;116;117;117;116;114;115;181;117 03:44:48 INFO - PROCESS | 7444 | |12;stanford-crypto-pbkdf2;150;151;152;152;154;154;150;152;152;150 03:44:48 INFO - PROCESS | 7444 | |13;stanford-crypto-sha256-iterative;68;62;62;62;61;62;61;64;62;60 03:44:48 INFO - PROCESS | 7444 | __end_tp_report 03:44:48 INFO - PROCESS | 7444 | __start_cc_report 03:44:48 INFO - PROCESS | 7444 | _x_x_mozilla_cycle_collect,1594 03:44:48 INFO - PROCESS | 7444 | __end_cc_report 03:44:48 INFO - PROCESS | 7444 | __startTimestamp1472899488068__endTimestamp 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | ------- Summary: start ------- 03:44:48 INFO - PROCESS | 7444 | Number of tests: 14 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#0] ai-astar Cycles:10 Average:108.20 Median:109.00 stddev:2.90 (2.7%) stddev-sans-first:0.33 03:44:48 INFO - PROCESS | 7444 | Values: 100.0 109.0 109.0 109.0 110.0 109.0 109.0 109.0 109.0 109.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:133.50 stddev:23.74 (17.8%) stddev-sans-first:24.22 03:44:48 INFO - PROCESS | 7444 | Values: 153.0 116.0 119.0 175.0 116.0 119.0 148.0 115.0 167.0 117.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.52 (0.9%) stddev-sans-first:1.41 03:44:48 INFO - PROCESS | 7444 | Values: 159.0 161.0 161.0 160.0 160.0 161.0 160.0 164.0 163.0 162.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#3] audio-fft Cycles:10 Average:80.70 Median:75.00 stddev:12.15 (16.2%) stddev-sans-first:12.76 03:44:48 INFO - PROCESS | 7444 | Values: 76.0 75.0 74.0 107.0 76.0 75.0 74.0 75.0 100.0 75.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#4] audio-oscillator Cycles:10 Average:95.10 Median:82.00 stddev:21.09 (25.7%) stddev-sans-first:21.75 03:44:48 INFO - PROCESS | 7444 | Values: 81.0 82.0 125.0 82.0 82.0 83.0 119.0 82.0 83.0 132.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.50 03:44:48 INFO - PROCESS | 7444 | Values: 118.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 117.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#6] imaging-darkroom Cycles:10 Average:150.20 Median:150.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 03:44:48 INFO - PROCESS | 7444 | Values: 150.0 150.0 150.0 150.0 151.0 149.0 149.0 150.0 151.0 152.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 03:44:48 INFO - PROCESS | 7444 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:50.00 stddev:0.52 (1.0%) stddev-sans-first:0.53 03:44:48 INFO - PROCESS | 7444 | Values: 50.0 49.0 50.0 49.0 49.0 50.0 50.0 49.0 50.0 50.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:0.70 (1.2%) stddev-sans-first:0.73 03:44:48 INFO - PROCESS | 7444 | Values: 59.0 60.0 58.0 59.0 58.0 58.0 58.0 59.0 58.0 59.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#10] stanford-crypto-aes Cycles:10 Average:74.70 Median:73.00 stddev:3.95 (5.4%) stddev-sans-first:4.14 03:44:48 INFO - PROCESS | 7444 | Values: 73.0 81.0 72.0 73.0 73.0 80.0 72.0 72.0 71.0 80.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#11] stanford-crypto-ccm Cycles:10 Average:123.70 Median:117.00 stddev:20.58 (17.6%) stddev-sans-first:21.73 03:44:48 INFO - PROCESS | 7444 | Values: 129.0 115.0 116.0 117.0 117.0 116.0 114.0 115.0 181.0 117.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.70 Median:152.00 stddev:1.49 (1.0%) stddev-sans-first:1.45 03:44:48 INFO - PROCESS | 7444 | Values: 150.0 151.0 152.0 152.0 154.0 154.0 150.0 152.0 152.0 150.0 03:44:48 INFO - PROCESS | 7444 | 03:44:48 INFO - PROCESS | 7444 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:62.00 stddev:2.22 (3.6%) stddev-sans-first:1.09 03:44:48 INFO - PROCESS | 7444 | Values: 68.0 62.0 62.0 62.0 61.0 62.0 61.0 64.0 62.0 60.0 03:44:48 INFO - PROCESS | 7444 | -------- Summary: end -------- 03:44:48 INFO - PROCESS | 7444 | 03:44:49 INFO - TEST-INFO | 7444: exit 0 03:44:49 INFO - TEST-OK | kraken | took 60156ms 03:44:49 INFO - SUITE-END | took 1173s 03:44:49 INFO - Completed test suite (00:19:34) 03:44:49 INFO - javascript benchmark 03:44:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3159.573457583227, 3188.936221275576, 3194.7603929705274, 3207.6311224209207, 3222.903312900613, 5556.777728890845, 5654.349749778777, 5672.971635141824, 5686.31764188297, 5688.345840228375, 5656.604037717361, 5716.485516303532, 5718.828435146942, 5722.921612832437, 5754.223179870716, 4967.950320496785, 5043.722595257259, 5150.562202212816, 5175.8188463403785, 5241.292425522543, 5111.718855462948, 5167.689938603696, 5170.819021334254, 5171.4311425743135, 5175.404828444721, 5047.722375269377, 5067.0, 5073.5180157885125, 5093.847184584469, 5147.433782283946, 5172.094883395391, 5178.611604129667, 5190.091733946544, 5193.065248255312, 5222.869428264324, 4948.134076536628, 5033.7734801934, 5076.771545280471, 5087.389513258433, 5096.490350964911, 5471.261379713727, 5484.533814625762, 5544.528715059225, 5549.5837812163845, 5564.221009058735, 6070.700170043403, 6075.568057134872, 6079.656228862454, 6085.532883440475, 6113.174721412597, 5859.882802343972, 5976.551758618078, 6004.519638428959, 6032.366601506867, 6098.26820781509, 5689.886202275931, 5719.28508936383, 5723.427657234327, 5724.341700704473, 5733.483986441199, 6199.597026193283, 6211.09939058843, 6215.595986260879, 6219.40915613016, 6227.06594010902, 5703.258576385043, 5864.794732184353, 5891.734871930774, 5894.8821023579285, 5980.73086711099, 6062.272527296753, 6064.585447382513, 6066.789429154379, 6100.145979562864, 6100.206973093558, 6031.276246850406, 6046.939530604725, 6047.244094488189, 6048.788292409745, 6049.153118563405, 5719.5138413235345, 5876.1479585459865, 5947.167396564484, 5947.524198064145, 6054.576179667469, 4623.861284161413, 4627.30590411441, 4628.3288923105965, 4633.93049104257, 4634.745089020136, 4106.3429851224255, 4112.835486580503, 4123.0310876943895, 4133.400656904732, 4146.854860079943, 5586.636868603528, 5705.657660540381, 5745.511631511368, 5759.8560035999435, 5782.045962416154, 6099.786507472306, 6101.054336577838, 6104.755809767648, 6106.0, 6130.478909292671, 5813.244278243801, 5815.476607105381, 5822.796202132905, 5823.5341172706085, 5829.125631155361, 5056.64603477753, 5171.146760784503, 5207.869803254873, 5208.9739551302, 5258.631895767336, 6261.060840873814, 6267.811965641038, 6274.592151510137, 6274.843128921813, 6277.309495955441, 6029.939700602938, 6078.027515597483, 6096.177016102947, 6099.512039036777, 6100.603460775035, 6031.728572214173, 6068.241469816273, 6093.025115981422, 6137.101203255209, 6138.07928810682, 5825.417458254141, 5887.8233652990475, 5903.498202652822, 5904.557158213065, 5904.793332233351, 6030.849228769316, 6059.575829691879, 6088.391160883876, 6094.421030002142, 6097.634141951497, 5880.970595146997, 5895.9705201475435, 5916.230889984275, 5934.3175534814045, 5935.495482884004, 5667.829965101054, 5915.911261330997, 5931.99156143448, 5942.64344139353, 5945.821625351246, 5768.94231057684, 5789.247397838254, 5818.6217895836635, 5883.264591926009, 5884.411558844253, 5957.285125784933, 5966.3735307793795, 6002.579819412774, 6011.909821352596, 6015.639061656315, 5574.247476590608, 5825.825225243249, 5827.679477628772, 5832.708364581838, 5839.766409343749], "value": 5560.518414549388, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.8264844519313, 642.8874946884296, 658.7299207324995, 662.8707402056598, 679.1001922452751, 34172.48741268883, 45727.77136114319, 46365.072698546006, 46381.0, 46437.7678111609, 40600.79699601498, 40738.37046518136, 40781.59218407815, 40858.38712419309, 40904.0, 8605.48367097973, 9282.675106371278, 9439.10328518793, 9447.196988256006, 9454.101860323259, 8640.913590864075, 8765.561721913911, 8769.517676527788, 11022.834657480144, 11339.943300283487, 11375.0, 11403.030742386907, 11463.770724585504, 11476.024537914287, 11481.827772583376, 10753.085987691056, 10805.189610779224, 10817.459127043616, 10842.72893177669, 10848.132149428027, 11068.225224234307, 11109.277896936674, 11130.220884538086, 11144.554217831277, 11146.331220126738, 36562.451563226576, 36897.63102368969, 36941.26117477635, 37069.0, 37231.813840930896, 3588.3540962626716, 3905.0, 4133.592930957005, 4193.5387107418155, 4519.367288579601, 34506.13734656654, 34940.82529587361, 35000.474992875126, 35234.0, 35798.821005895064, 4742.33607294979, 4823.1800593899125, 4824.855254342376, 4844.20070688336, 4933.901321973541, 386897.0, 387101.9999999972, 387209.0, 388764.0, 391566.0, 61287.69356153191, 61415.692921536, 61475.38524614786, 61644.38355616386, 61646.691766540884, 39527.01182470461, 40156.397654035216, 40349.59650403517, 64889.67555162194, 64899.35100649027, 15606.84393156054, 15919.761203581955, 15933.521994340188, 15933.601659958595, 15950.441734539236, 11866.76266474683, 11873.406329683481, 11946.34295113777, 11968.222065565711, 11974.58088966882, 18779.342723004902, 19881.60236795256, 21541.56916861654, 21558.245461408776, 21748.565028699653, 7314.817129571803, 7379.262073792685, 7733.4973226740085, 7735.3424958878, 7847.882281765778, 1017.2370721958531, 1022.6267412394571, 1095.9835602465969, 1108.5344155454568, 1146.931184128955, 33848.49227261593, 34113.317733645184, 34228.82885585556, 34271.14322141917, 34643.65356346455, 1158.1892675127444, 1187.329159025148, 1228.281455348631, 1242.4719494215103, 1245.725940293134, 17862.910685446488, 17937.641247174983, 17999.470045046317, 18040.819591803913, 18124.284521673024, 652117.0, 653310.0, 656095.0, 656108.0, 662148.0, 62759.43101422501, 63455.6827215861, 63526.0, 63531.36468635254, 63542.046869296995, 1318.1234479071502, 1439.9064060836222, 1450.2386247220084, 1459.1463993563673, 1478.8890833187554, 33165.33669326648, 34966.825165874005, 35276.82361588176, 35550.0, 35702.0, 52875.206871897724, 53606.1959070622, 53628.19557706559, 53725.462745372046, 53756.46243537514, 467256.0, 468367.0, 468512.0, 469577.0, 471792.0, 30635.387292254476, 30669.8466507666, 30916.690833091383, 31376.0, 31568.684313157493, 51300.74349628377, 51588.22617660813, 52002.47997519976, 52041.7397913008, 52165.47834521606, 42688.10225173449, 43679.0, 44386.556134438244, 45297.0, 45534.316985244586, 36764.63235367612, 38408.423873642474, 43360.349594756735, 43511.78244108759, 45233.32150017818, 42962.140757184054, 43047.56952430561, 43687.78156109199, 43968.12063758643, 44326.0, 37775.62224377721, 39176.60823391729, 39981.600183998955, 40136.0, 40538.0, 29859.0, 29882.252943675714, 30148.84925575446, 30230.0, 30327.393452131277], "value": 21439.08794490221, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.7505458978957, 422.69777109366794, 425.97444153350796, 432.2112145334765, 441.97348159110453, 16127.0, 21258.57482850344, 21271.468213294676, 21536.784632153693, 21578.352649420503, 20169.294074707388, 20455.079521421536, 20482.2831200908, 20876.0, 20892.268770593033, 4137.400076988834, 5899.970500147515, 5949.375315591855, 5997.490213331872, 6150.784722534712, 6079.726412311435, 6153.538484613649, 6199.0, 6220.973539365999, 6246.031865060922, 6115.327313995457, 6144.41628045335, 6230.657313847737, 6237.3450787667325, 6257.62454252744, 6017.939820601781, 6234.968825155868, 6244.157038799772, 6255.562110652256, 6265.5614107012525, 6079.300880398722, 6126.693665316761, 6134.723937422827, 6134.846628834271, 6135.2024236849375, 14424.423023079064, 14652.41390344385, 22944.770552294598, 25106.372340691447, 25658.48683026348, 2410.433548116191, 2484.1305543059966, 2513.6229565565313, 2582.2382397192873, 2768.5708715149185, 10764.515596798163, 15013.0, 16054.75917861233, 17157.313707451685, 17963.550911227194, 2094.3402828109097, 2118.0, 2144.0994782191515, 2236.250855963238, 2275.7496675365696, 97768.0223197766, 101559.49220253926, 103081.48459257731, 104564.95435045628, 105088.0, 18278.81721182771, 18352.54118647018, 18570.050144734607, 18669.253229870923, 18992.43022709321, 33549.49675754866, 33866.0, 33881.83059084738, 34742.82628586841, 34787.826060869535, 9833.0, 9992.10071093605, 10036.598536058476, 10093.899061009295, 10155.492225388849, 8192.30365418946, 8277.420580559303, 8278.503289802631, 8397.70608028716, 8428.78928026792, 7581.886271705929, 9112.362134650512, 9321.394109382869, 9357.766055848659, 9582.760430989281, 3587.461880717913, 3596.478510615946, 3622.5109610202812, 3632.6185750496347, 3783.281176576442, 984.3503287829998, 994.5275993902839, 1001.9699409017741, 1057.836379982029, 1087.0596933652491, 20826.47933801637, 20967.26614568483, 21274.043267885016, 21352.679709804364, 21355.57288854214, 966.9033096690275, 985.7387792235063, 1009.7828966772179, 1046.733083063829, 1084.2897901874285, 10688.89311106879, 11851.170418070824, 12083.214591051554, 12112.878871211351, 12186.086043546593, 572739.0, 573678.0, 573715.0, 575166.0, 591557.0, 87191.0, 90389.54805226064, 91057.54471227733, 92879.53560232156, 93023.0, 1031.5770534080991, 1043.3062013760764, 1052.400131924811, 1064.7284942339654, 1071.2822408985999, 18162.818371816113, 18164.72752908681, 18322.26710931548, 18755.93305021234, 19204.615907682048, 44393.0, 44566.0, 44682.0, 44784.10431791281, 45571.31643025291, 61346.69326653517, 62584.37415625968, 62747.37252627415, 63080.0, 63196.684016579624, 9452.790985735526, 9550.092741189575, 9582.808343833223, 9593.42439453607, 9607.90392096098, 8248.711295104642, 13334.933325333312, 13574.93212533931, 14000.649983750487, 14539.636509087357, 12273.140880138304, 12478.0, 12506.624801255977, 12659.746805064031, 12739.108262421541, 11578.421078946187, 11869.5845645402, 11903.821442678194, 11927.76144477123, 12002.339871306813, 11413.429328533706, 12598.811017834923, 12673.366331683195, 12935.870641293843, 12949.54676586315, 9204.677836275698, 9260.675876344565, 9383.812323753351, 9387.483688397204, 9425.05749425052, 10685.51915163804, 12301.384930753247, 12319.0, 12345.197562158432, 12638.936805315914], "value": 10616.538808865851, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1427.8286605607325, 1498.1086253679057, 1688.1812321024302, 1881.1346780480978, 1896.2225487550106, 9383.343165978384, 10055.095041446277, 10063.698089057321, 10069.798604027925, 10081.647142350019, 8510.404271700983, 9145.634174633007, 9307.441553506795, 9362.0, 9405.247580193585, 2555.2589748972823, 2583.547879121156, 2583.586626139818, 2590.8575028373434, 2591.3003489058037, 2503.5493611149986, 2563.3207200091897, 2572.588385858263, 2573.7812285955624, 2583.147561304765, 2553.885075171622, 2582.535143674138, 2590.870456477178, 2592.546304396723, 2592.909248176314, 2467.568175569277, 2478.950420991579, 2484.453420247534, 2500.8874600643016, 2509.2597683683357, 2410.746871578494, 2418.6130219165025, 2420.636904464327, 2433.8783060847063, 2475.6410320503605, 9746.707598772047, 9755.170810481117, 9800.460974646392, 9817.950910245403, 9853.211743060538, 5762.827115186551, 5955.464008239279, 5956.16613674086, 5959.553033522459, 5963.0, 3659.981700091509, 3733.757305775116, 3772.5472943246714, 3791.9241615167816, 3865.188310454808, 1187.6140254417262, 1213.8725433829409, 1243.3286025546195, 1247.4261839553817, 1251.5744646820124, 13215.0, 13469.797953030713, 13490.932545337211, 13880.514182003682, 14102.71794564103, 5029.84910452687, 5138.563222126087, 5171.095058364846, 5188.143956247252, 5288.735563221823, 10972.0, 11003.504842282116, 11008.559657613605, 11014.06380457654, 11045.558177672803, 5865.912011319833, 5869.589128761031, 5874.030784920526, 5876.0, 5876.382979787061, 4605.378273933044, 5262.71055091966, 5327.946720532745, 5440.483154100354, 5459.317585301837, 4202.873913782591, 4283.828646854154, 4336.219480493478, 4348.043430445297, 4371.409859668967, 1269.6254604891578, 1273.4969686973593, 1280.9487620495263, 1295.533607901155, 1306.2293246984325, 878.5914549734404, 1082.4966390628394, 1112.8386383974441, 1122.4163435013747, 1123.9100871930389, 6561.475081993429, 6833.829154271084, 6861.0, 6862.954659561515, 6939.271376505495, 500.8472415913109, 501.7541404711665, 510.61448606302747, 513.5839969624616, 513.7354262554787, 4612.939024024426, 4760.7619619018915, 4769.689970151929, 4835.20219163834, 4869.269609558594, 12097.57658481949, 12268.631941041784, 12365.196262242926, 12372.257664540157, 12421.875781242252, 11639.243449175776, 12115.39423028845, 12123.575674851338, 12457.0, 12500.437480313407, 8756.693515726918, 8917.42036767595, 8937.419067760575, 8942.552872356357, 8942.776430589287, 6959.130108736408, 6964.338387853146, 7138.678759455733, 7267.890981635385, 7340.743073992385, 11624.476898539755, 11681.707957301136, 11791.056715462571, 11833.053355731348, 11881.405929703307, 11082.224244303145, 11350.602728904449, 11398.886011139783, 11550.075993920635, 11733.530658773554, 8055.234752698484, 8230.382721295808, 8302.128276531119, 8429.536375499407, 8702.82594348122, 9460.479673618018, 9524.95237523808, 9536.57085431171, 9542.284328675238, 9553.426794392359, 8656.913430865863, 8693.261072808882, 8728.0, 8739.737807865775, 8772.473651580925, 8270.503769773833, 8290.295324897452, 8504.82969493008, 8534.0, 8618.095100014409, 8157.8368432632205, 8179.813926980673, 8221.630026648934, 8222.547759873265, 8224.547649879314, 6002.129691194665, 6024.608400454132, 6159.29168145669, 6166.136740856194, 6238.937610624017, 5809.738561764815, 5871.209281169285, 5932.584719069624, 6089.969550152221, 6109.358517355614], "value": 5056.864967520344, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.7022771099987, 364.456959130895, 366.91927775889314, 367.14272174472603, 368.3369934118586, 5540.944590554093, 5641.771500257401, 5649.745761440735, 5651.717414129293, 5652.06740887753, 5421.457854214576, 5545.584081193906, 5595.0, 5604.206653870753, 5615.511889349325, 4355.0, 4370.169667763115, 4395.637352420744, 4410.338449232609, 4425.225585522537, 4345.326474396474, 4350.934735978963, 4360.56394360563, 4360.956390436103, 4368.759718215497, 4343.522212556616, 4345.521992580813, 4356.128774245148, 4371.956280437187, 4405.185040767468, 4237.470316210473, 4358.716683415583, 4381.189479946188, 4388.670849686287, 4399.5160532341415, 3926.391409331558, 3932.842686292545, 3939.212157568498, 3946.9013274668077, 3951.6641085507767, 5435.1847222916485, 5460.563154947635, 5494.972525137348, 5496.4716230534, 5505.532029777473, 4946.526736631684, 4991.550760431579, 5027.321311622921, 5051.520105589963, 5074.264231686385, 5729.799457019026, 5747.3677895431465, 5757.856053598652, 5767.221425107598, 5774.711264436762, 4321.135772845443, 4334.891627709301, 4337.761423121727, 4345.782710864476, 4363.52001279859, 5710.257666503369, 5862.648241105548, 5879.942429936409, 6106.641347259034, 6110.8834494408175, 4341.37050127736, 4349.695521313478, 4350.412694286294, 4386.692931494765, 4409.404730361424, 4469.374287599739, 4477.104579084196, 4478.417805685305, 4489.057297967398, 4493.303537951622, 4803.567678908915, 4904.337914381585, 4935.457099719028, 5015.3229314042865, 5023.321851550067, 5819.709014549256, 5823.417658234142, 5833.125031245348, 5834.27071616048, 5888.646681199141, 1545.5749668841158, 1561.773542836305, 1562.7577725452345, 1565.1548163991317, 1565.8590726834639, 987.683941138843, 1045.0, 1055.904968552834, 1057.8519007338978, 1061.3578784835063, 5666.546676265889, 5667.688277144796, 5676.716164191774, 5705.4009329019855, 5707.885842283131, 5916.319623243296, 6002.489788368038, 6029.161105862754, 6054.606450580698, 6118.632882027093, 5649.378568357477, 5658.726786473011, 5667.603267771216, 5683.5737319700365, 5742.885142297214, 3141.413586139031, 3176.936461270762, 3215.7909735867092, 3220.5652236947712, 3222.7099561039618, 6030.698465076729, 6109.816705498843, 6127.295361033449, 6164.229471316085, 6260.530460215411, 6027.397260273938, 6034.48706859913, 6041.6375017499095, 6056.454919057218, 6088.939110608837, 6005.048359283251, 6096.78661246872, 6181.196444462281, 6188.762247550508, 6210.254769427697, 5887.3523912370465, 5913.0, 5920.921121017133, 5936.079907614327, 5955.344912059757, 5646.616578938187, 5865.562937080272, 5887.764489420375, 5889.676067816311, 5896.410358964069, 5705.600607957569, 6196.47329976939, 6201.379862013762, 6214.8135555933395, 6216.968915155395, 4258.5741425858405, 4272.632757517565, 4285.46431696038, 4292.141571685738, 4299.9355009674255, 5829.183914251923, 6091.269047714302, 6108.602940808833, 6110.7861224856915, 6139.72371243304, 5831.125331200354, 5832.941670583409, 5884.264466941632, 5885.9405307043435, 5915.852103697442, 5840.707964601838, 5926.7925622603, 5943.227380440688, 5949.286085669747, 5956.5532585055425, 5605.215269862141, 5846.327672317738, 5861.501772349227, 5871.4715675589405, 5916.787058653052, 5907.763689452374, 5921.230240068763, 5935.910961335497, 5938.654231578416, 5950.821475355746, 5674.184261036594, 5818.534517238695, 5828.213191219301, 5862.530997520103, 5886.499647530008], "value": 4501.406717450985, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.8207153390488, 798.3174385900055, 806.6128258435947, 831.0775039705921, 905.3798148268435, 15285.0, 16127.516174514754, 16246.187690615481, 16257.918710406446, 16309.75535366969, 16041.438549650735, 16286.104264265461, 16306.592335191626, 16311.265993030313, 16448.095354755485, 876.1063715010686, 895.928325733943, 896.368060517337, 900.7928176519405, 900.9234215091725, 898.8112496375736, 899.2221728205121, 901.1889299630352, 901.7430032440756, 902.2826852652157, 944.7496413450441, 945.3146468810093, 945.7824700318931, 945.9952700236489, 946.0633972367341, 942.2038377570941, 944.3106532231475, 945.135201290818, 945.3477100800426, 945.6973768394116, 690.440742998173, 691.9515633905629, 695.2873304862557, 695.3185877839695, 695.6035060015793, 17269.222884970208, 17303.0, 17458.563535911577, 17468.737968930476, 17488.91255543727, 16271.755923661154, 16536.917315413466, 16722.331106755715, 17031.57421064471, 17260.39588614405, 13079.28063956482, 13322.000849936197, 13389.66525836862, 13395.86604133956, 13563.45746170152, 4082.6529744971713, 4115.73247738896, 4140.3168477201525, 4147.274227010292, 4195.643370313527, 87965.0, 88907.0, 89404.0, 90788.0066596337, 91076.0, 11900.821487677691, 11910.46402911871, 12092.274463532216, 12447.564335248404, 12465.93767031159, 23241.0, 23592.05631774744, 23662.881685591463, 23729.644055339184, 23737.05051797909, 9305.441673499612, 9386.85919711205, 9500.144986951207, 9553.283503737108, 9591.808163836684, 4759.690620109682, 4781.6891902026255, 4789.688670236424, 4943.036107958914, 5024.824131155392, 3827.8660246891227, 3976.642102210757, 4003.3594624859884, 4005.9799701001893, 4007.979960100239, 726.4370113162341, 737.9372753315921, 750.6096829648552, 755.9433042521833, 761.885717142422, 754.7547047209679, 769.6959700918106, 808.4825711544617, 836.1471299274828, 838.8783626374142, 10820.891791082146, 10830.296030757976, 10850.837237441445, 11638.825417618742, 12013.0, 784.7998760316196, 809.2312303311877, 819.1644522586928, 825.938054645904, 849.0490650471388, 5079.742446755819, 5373.3551973762615, 5390.19147127934, 5471.151971444432, 5521.889562208733, 107803.0, 121138.0, 123347.0, 126302.0, 127313.0, 103344.0, 106248.46875765572, 107057.0, 107291.46354268178, 110511.0, 90919.0, 91389.0, 93319.0, 93764.0, 94670.5266473677, 9854.802903941738, 10206.13247873939, 10310.742231444274, 10337.121344685786, 10341.793164136525, 53647.463525365805, 54207.0, 54343.72828135834, 54594.0, 56755.54849093041, 74095.62952185204, 75298.24701752912, 78202.6089869547, 78654.0, 78850.6057469709, 12907.483700651868, 13046.152000119962, 13096.476140954635, 13169.473221071434, 13234.338283086, 13768.311584420939, 13848.238346891017, 13908.86091139116, 14325.426982920566, 14688.853111469176, 13478.46086156582, 13955.790663139858, 14117.647058823612, 14260.358283877044, 14345.426182952982, 12597.496100155891, 13231.470741170246, 13362.997775166707, 13515.872150103694, 14416.846652268003, 13225.0, 13702.10936289138, 13732.725345493234, 13996.16023038621, 14339.713205736036, 11000.284981476498, 11106.722331941766, 11613.30320180792, 11745.882541174478, 11939.820902686293, 11374.601888933848, 11587.0, 11626.302421854378, 11849.52601895949, 11870.169088164092], "value": 8204.728144134335, "unit": "score"}], "name": "dromaeo_css", "value": 7863.337559057756, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 109.0, 109.0, 109.0, 110.0, 109.0, 109.0, 109.0, 109.0, 109.0], "value": 108.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 116.0, 119.0, 175.0, 116.0, 119.0, 148.0, 115.0, 167.0, 117.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 161.0, 161.0, 160.0, 160.0, 161.0, 160.0, 164.0, 163.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 74.0, 107.0, 76.0, 75.0, 74.0, 75.0, 100.0, 75.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 125.0, 82.0, 82.0, 83.0, 119.0, 82.0, 83.0, 132.0], "value": 95.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 150.0, 150.0, 151.0, 149.0, 149.0, 150.0, 151.0, 152.0], "value": 150.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 50.0, 49.0, 49.0, 50.0, 50.0, 49.0, 50.0, 50.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 58.0, 59.0, 58.0, 58.0, 58.0, 59.0, 58.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 81.0, 72.0, 73.0, 73.0, 80.0, 72.0, 72.0, 71.0, 80.0], "value": 74.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 115.0, 116.0, 117.0, 117.0, 116.0, 114.0, 115.0, 181.0, 117.0], "value": 123.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 151.0, 152.0, 152.0, 154.0, 154.0, 150.0, 152.0, 152.0, 150.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 62.0, 62.0, 62.0, 61.0, 62.0, 61.0, 64.0, 62.0, 60.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1462.2, "alertThreshold": 2.0}]} 03:44:49 INFO - Return code: 0 03:44:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:44:49 INFO - # TBPL SUCCESS # 03:44:49 INFO - Running post-action listener: _resource_record_post_action 03:44:49 INFO - [mozharness: 2016-09-03 10:44:49.383877Z] Finished run-tests step (success) 03:44:49 INFO - Running post-run listener: _resource_record_post_run 03:44:49 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 688128; Write bytes: 327770112; Read time: 52; Write time: 300028 03:44:49 INFO - TinderboxPrint: CPU usage
12.9% 03:44:49 INFO - TinderboxPrint: I/O read bytes / time
688,128 / 52 03:44:49 INFO - TinderboxPrint: I/O write bytes / time
327,770,112 / 300,028 03:44:49 INFO - TinderboxPrint: CPU idle
8,276.2 (87.1%) 03:44:49 INFO - TinderboxPrint: CPU user
1,184.4 (12.5%) 03:44:49 INFO - TinderboxPrint: Swap in / out
0 / 0 03:44:49 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78721024; Read time: 0; Write time: 58048 03:44:49 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 679936; Write bytes: 247934976; Read time: 36; Write time: 241764 03:44:50 INFO - Running post-run listener: _upload_blobber_files 03:44:50 INFO - Blob upload gear active. 03:44:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:44:50 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 03:44:50 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'] 03:44:50 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 03:44:50 INFO - (blobuploader) - INFO - Open directory for files ... 03:44:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:44:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:44:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:51 INFO - (blobuploader) - INFO - Done attempting. 03:44:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:44:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:44:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:55 INFO - (blobuploader) - INFO - Done attempting. 03:44:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:44:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:44:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:44:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:44:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:44:56 INFO - (blobuploader) - INFO - Done attempting. 03:44:56 INFO - (blobuploader) - INFO - Iteration through files over. 03:44:56 INFO - Return code: 0 03:44:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:44:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:44:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/16dfb166ad66f51bd2c31070a88d7a493d0d79e448fba6292ce4e5a2cb4e0d48a8e97e7d282805b837497068d62e7b98e6d56fd21f09121ebcc6a4aa415e624c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8ddb34454568a1290359fd70363f975ad66a457af35de9d75e1190b33a590728e0cc7130510313cb6311d94749cb4624fdc34be6f143f82ee47ab113356dd17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e69efdb92b71f3cfb7dc0c3b0a1b3c7f9909029cb394c13ec635e9df1f1fd857c513a8cd5f9d2a8f33f17f2a59f3d72021d03c70b24cf0317bd9c09dcce21a8d"} 03:44:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:44:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:44:56 INFO - Contents: 03:44:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/16dfb166ad66f51bd2c31070a88d7a493d0d79e448fba6292ce4e5a2cb4e0d48a8e97e7d282805b837497068d62e7b98e6d56fd21f09121ebcc6a4aa415e624c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8ddb34454568a1290359fd70363f975ad66a457af35de9d75e1190b33a590728e0cc7130510313cb6311d94749cb4624fdc34be6f143f82ee47ab113356dd17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e69efdb92b71f3cfb7dc0c3b0a1b3c7f9909029cb394c13ec635e9df1f1fd857c513a8cd5f9d2a8f33f17f2a59f3d72021d03c70b24cf0317bd9c09dcce21a8d"} 03:44:56 INFO - Running post-run listener: copy_logs_to_upload_dir 03:44:56 INFO - Copying logs to upload dir... 03:44:56 INFO - mkdir: /builds/slave/test/build/upload/logs 03:44:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.865029 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 58 secs) (at 2016-09-03 03:44:56.076978) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 03:44:56.080182) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684 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/16dfb166ad66f51bd2c31070a88d7a493d0d79e448fba6292ce4e5a2cb4e0d48a8e97e7d282805b837497068d62e7b98e6d56fd21f09121ebcc6a4aa415e624c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8ddb34454568a1290359fd70363f975ad66a457af35de9d75e1190b33a590728e0cc7130510313cb6311d94749cb4624fdc34be6f143f82ee47ab113356dd17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e69efdb92b71f3cfb7dc0c3b0a1b3c7f9909029cb394c13ec635e9df1f1fd857c513a8cd5f9d2a8f33f17f2a59f3d72021d03c70b24cf0317bd9c09dcce21a8d"} build_url:https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011230 build_url: 'https://queue.taskcluster.net/v1/task/CjJylzlOR_O887hXHVW16g/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/16dfb166ad66f51bd2c31070a88d7a493d0d79e448fba6292ce4e5a2cb4e0d48a8e97e7d282805b837497068d62e7b98e6d56fd21f09121ebcc6a4aa415e624c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d8ddb34454568a1290359fd70363f975ad66a457af35de9d75e1190b33a590728e0cc7130510313cb6311d94749cb4624fdc34be6f143f82ee47ab113356dd17", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e69efdb92b71f3cfb7dc0c3b0a1b3c7f9909029cb394c13ec635e9df1f1fd857c513a8cd5f9d2a8f33f17f2a59f3d72021d03c70b24cf0317bd9c09dcce21a8d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-03 03:44:56.106667) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:44:56.107204) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1472897815.79787-104217684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004394 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-03 03:44:56.190287) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 03:44:56.190664) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-03 03:44:56.191018) ========= ========= Total master_lag: 0.19 =========