builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1473720170.03 results: success (0) buildid: 20160912133916 builduid: d90977b7fea24f9680fbdf7141d29f5b revision: cdf6988ace7810606807d1a1d73cfb06b08dc93a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.026372) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.026850) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.027139) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.042031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.042375) ========= 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=8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-12 15:42:50-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 112M=0s 2016-09-12 15:42:50 (112 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.090650 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.147342) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.147649) ========= 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=8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021160 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.198716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.199124) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cdf6988ace7810606807d1a1d73cfb06b08dc93a --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 cdf6988ace7810606807d1a1d73cfb06b08dc93a --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=8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-12 15:42:51,079 truncating revision to first 12 chars 2016-09-12 15:42:51,079 Setting DEBUG logging. 2016-09-12 15:42:51,079 attempt 1/10 2016-09-12 15:42:51,079 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cdf6988ace78?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 15:42:51,391 unpacking tar archive at: mozilla-aurora-cdf6988ace78/testing/mozharness/ program finished with exit code 0 elapsedTime=0.659623 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.873222) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.873644) ========= script_repo_revision: cdf6988ace7810606807d1a1d73cfb06b08dc93a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.874139) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.874462) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 15:42:50.884914) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-09-12 15:42:50.885411) ========= /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=8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:42:51 INFO - MultiFileLogger online at 20160912 15:42:51 in /builds/slave/test 15:42:51 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 15:42:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:42:51 INFO - {'append_to_log': False, 15:42:51 INFO - 'base_work_dir': '/builds/slave/test', 15:42:51 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:42:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:42:51 INFO - 'branch': 'Mozilla-Aurora', 15:42:51 INFO - 'buildbot_json_path': 'buildprops.json', 15:42:51 INFO - 'config_files': ('talos/linux_config.py',), 15:42:51 INFO - 'default_actions': ('clobber', 15:42:51 INFO - 'read-buildbot-config', 15:42:51 INFO - 'download-and-extract', 15:42:51 INFO - 'populate-webroot', 15:42:51 INFO - 'create-virtualenv', 15:42:51 INFO - 'install', 15:42:51 INFO - 'run-tests'), 15:42:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:42:51 INFO - 'download_minidump_stackwalk': True, 15:42:51 INFO - 'download_symbols': 'ondemand', 15:42:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:42:51 INFO - 'tooltool.py': '/tools/tooltool.py', 15:42:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:42:51 INFO - '/tools/misc-python/virtualenv.py')}, 15:42:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:42:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:42:51 INFO - 'installer_path': 'installer.exe', 15:42:51 INFO - 'log_level': 'info', 15:42:51 INFO - 'log_name': 'talos', 15:42:51 INFO - 'log_to_console': True, 15:42:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:42:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:42:51 INFO - 'opt_config_files': (), 15:42:51 INFO - 'pip_index': False, 15:42:51 INFO - 'sps_profile': False, 15:42:51 INFO - 'sps_profile_interval': 0, 15:42:51 INFO - 'suite': 'dromaeojs', 15:42:51 INFO - 'system_bits': '32', 15:42:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:42:51 INFO - 'title': 'talos-linux64-ix-038', 15:42:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:42:51 INFO - 'use_talos_json': True, 15:42:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:42:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:42:51 INFO - 'work_dir': 'build'} 15:42:51 INFO - [mozharness: 2016-09-12 22:42:51.853255Z] Running clobber step. 15:42:51 INFO - Running pre-action listener: _resource_record_pre_action 15:42:51 INFO - Running main action method: clobber 15:42:51 INFO - rmtree: /builds/slave/test/build 15:42:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:42:52 INFO - Running post-action listener: _resource_record_post_action 15:42:52 INFO - [mozharness: 2016-09-12 22:42:52.429723Z] Finished clobber step (success) 15:42:52 INFO - [mozharness: 2016-09-12 22:42:52.429825Z] Running read-buildbot-config step. 15:42:52 INFO - Running pre-action listener: _resource_record_pre_action 15:42:52 INFO - Running main action method: read_buildbot_config 15:42:52 INFO - Using buildbot properties: 15:42:52 INFO - { 15:42:52 INFO - "project": "", 15:42:52 INFO - "product": "firefox", 15:42:52 INFO - "script_repo_revision": "production", 15:42:52 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 15:42:52 INFO - "repository": "", 15:42:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 15:42:52 INFO - "buildid": "20160912133916", 15:42:52 INFO - "pgo_build": "True", 15:42:52 INFO - "basedir": "/builds/slave/test", 15:42:52 INFO - "buildnumber": 467, 15:42:52 INFO - "slavename": "talos-linux64-ix-038", 15:42:52 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:42:52 INFO - "platform": "ubuntu64_hw", 15:42:52 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 15:42:52 INFO - "branch": "mozilla-aurora", 15:42:52 INFO - "repo_path": "releases/mozilla-aurora", 15:42:52 INFO - "revision": "cdf6988ace7810606807d1a1d73cfb06b08dc93a", 15:42:52 INFO - "stage_platform": "linux64-pgo", 15:42:52 INFO - "builduid": "d90977b7fea24f9680fbdf7141d29f5b", 15:42:52 INFO - "slavebuilddir": "test" 15:42:52 INFO - } 15:42:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 15:42:52 INFO - Running post-action listener: _resource_record_post_action 15:42:52 INFO - [mozharness: 2016-09-12 22:42:52.432286Z] Finished read-buildbot-config step (success) 15:42:52 INFO - [mozharness: 2016-09-12 22:42:52.432376Z] Running download-and-extract step. 15:42:52 INFO - Running pre-action listener: _resource_record_pre_action 15:42:52 INFO - Running main action method: download_and_extract 15:42:52 INFO - mkdir: /builds/slave/test/build/tests 15:42:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:42:52 INFO - https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:42:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:42:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:42:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:42:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:42:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:42:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:42:52 INFO - retry: Failed, sleeping 30 seconds before retrying 15:43:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:43:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:43:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:43:22 INFO - retry: Failed, sleeping 60 seconds before retrying 15:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:44:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:44:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:44:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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! 15:44:22 INFO - Caught exception: HTTP Error 404: Not Found 15:44:22 INFO - Caught exception: HTTP Error 404: Not Found 15:44:22 INFO - Caught exception: HTTP Error 404: Not Found 15:44:22 INFO - trying https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:44:22 INFO - Downloading https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:44:23 INFO - Downloaded 1361 bytes. 15:44:23 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 15:44:23 INFO - Using the following test package requirements: 15:44:23 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 15:44:23 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 15:44:23 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 15:44:23 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:23 INFO - u'jsshell-linux-x86_64.zip'], 15:44:23 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 15:44:23 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 15:44:23 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 15:44:23 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 15:44:23 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 15:44:23 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 15:44:23 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 15:44:23 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:44:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:44:23 INFO - https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:44:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:44:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:44:23 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:23 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:44:23 INFO - retry: Failed, sleeping 30 seconds before retrying 15:44:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:44:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:44:53 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:44:53 INFO - retry: Failed, sleeping 60 seconds before retrying 15:45:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:45:53 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:45:53 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:45:53 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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! 15:45:53 INFO - Caught exception: HTTP Error 404: Not Found 15:45:53 INFO - Caught exception: HTTP Error 404: Not Found 15:45:53 INFO - Caught exception: HTTP Error 404: Not Found 15:45:53 INFO - trying https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:45:53 INFO - Downloading https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:45:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:45:54 INFO - Downloaded 21770864 bytes. 15:45:54 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'] 15:45:54 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 15:45:55 INFO - Return code: 0 15:45:55 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 15:45:55 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')]} 15:45:55 INFO - https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:45:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:45:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:45:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:45:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:45:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:45:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:45:55 INFO - retry: Failed, sleeping 30 seconds before retrying 15:46:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:46:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:46:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:46:25 INFO - retry: Failed, sleeping 60 seconds before retrying 15:47:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:47:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:47:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:47:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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! 15:47:25 INFO - Caught exception: HTTP Error 404: Not Found 15:47:25 INFO - Caught exception: HTTP Error 404: Not Found 15:47:25 INFO - Caught exception: HTTP Error 404: Not Found 15:47:25 INFO - trying https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 15:47:25 INFO - Downloading https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:47:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:47:26 INFO - Downloaded 21770864 bytes. 15:47:26 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'] 15:47:26 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 15:47:27 INFO - Return code: 0 15:47:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:27 INFO - https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:47:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:47:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:47:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:47:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:47:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:47:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:47:27 INFO - retry: Failed, sleeping 30 seconds before retrying 15:47:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:47:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:47:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:47:57 INFO - retry: Failed, sleeping 60 seconds before retrying 15:48:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:48:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:48:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:48:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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! 15:48:57 INFO - Caught exception: HTTP Error 404: Not Found 15:48:57 INFO - Caught exception: HTTP Error 404: Not Found 15:48:57 INFO - Caught exception: HTTP Error 404: Not Found 15:48:57 INFO - trying https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 15:48:57 INFO - Downloading https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:48:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:48:58 INFO - Downloaded 14413740 bytes. 15:48:58 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'] 15:48:58 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 15:48:58 INFO - Return code: 0 15:48: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')]} 15:48:58 INFO - https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:48:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:48:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:48:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:48:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:48:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:48:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:48:58 INFO - retry: Failed, sleeping 30 seconds before retrying 15:49:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:49:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:49:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:49:28 INFO - retry: Failed, sleeping 60 seconds before retrying 15:50:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:50:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:50:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:50:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ArRr8b29QBi9SfhNpWBe4A/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! 15:50:28 INFO - Caught exception: HTTP Error 404: Not Found 15:50:28 INFO - Caught exception: HTTP Error 404: Not Found 15:50:28 INFO - Caught exception: HTTP Error 404: Not Found 15:50:28 INFO - trying https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:50:28 INFO - Downloading https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:50:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/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 15:50:29 INFO - Downloaded 63915893 bytes. 15:50:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:50:29 INFO - mkdir: /builds/slave/test/properties 15:50:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:50:29 INFO - Writing to file /builds/slave/test/properties/build_url 15:50:29 INFO - Contents: 15:50:29 INFO - build_url:https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 15:50:30 INFO - Running post-action listener: _resource_record_post_action 15:50:30 INFO - Running post-action listener: set_extra_try_arguments 15:50:30 INFO - [mozharness: 2016-09-12 22:50:30.641509Z] Finished download-and-extract step (success) 15:50:30 INFO - [mozharness: 2016-09-12 22:50:30.641747Z] Running populate-webroot step. 15:50:30 INFO - Running pre-action listener: _resource_record_pre_action 15:50:30 INFO - Running main action method: populate_webroot 15:50:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:50:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:50:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:50:30 INFO - '--tppagecycles', 15:50:30 INFO - '7'], 15:50:30 INFO - 'tests': ['tsvgm']}}, 15:50:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:50:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:50:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:50:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:50:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:50:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:50:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:30 INFO - 'talos_options': ['--disable-e10s'], 15:50:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:50:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:30 INFO - 'talos_options': ['--disable-e10s'], 15:50:30 INFO - 'tests': ['damp', 'tps']}, 15:50:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:30 INFO - 'tests': ['damp', 'tps']}, 15:50:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:50:30 INFO - 'tests': ['dromaeo_dom']}, 15:50:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:50:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:50:30 INFO - 'tests': ['basic_compositor_video']}, 15:50:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:50:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:50:30 INFO - 'tests': ['a11yr', 15:50:30 INFO - 'ts_paint', 15:50:30 INFO - 'tpaint', 15:50:30 INFO - 'sessionrestore', 15:50:30 INFO - 'sessionrestore_no_auto_restore', 15:50:30 INFO - 'tabpaint']}, 15:50:30 INFO - 'other-e10s': {'tests': ['a11yr', 15:50:30 INFO - 'ts_paint', 15:50:30 INFO - 'tpaint', 15:50:30 INFO - 'sessionrestore', 15:50:30 INFO - 'sessionrestore_no_auto_restore', 15:50:30 INFO - 'tabpaint']}, 15:50:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:50:30 INFO - 'ts_paint', 15:50:30 INFO - 'tpaint', 15:50:30 INFO - 'sessionrestore', 15:50:30 INFO - 'sessionrestore_no_auto_restore', 15:50:30 INFO - 'tabpaint']}, 15:50:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:50:30 INFO - 'ts_paint', 15:50:30 INFO - 'tpaint', 15:50:30 INFO - 'sessionrestore', 15:50:30 INFO - 'sessionrestore_no_auto_restore', 15:50:30 INFO - 'tabpaint']}, 15:50:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:50:30 INFO - 'tests': ['a11yr', 15:50:30 INFO - 'ts_paint', 15:50:30 INFO - 'tpaint', 15:50:30 INFO - 'sessionrestore', 15:50:30 INFO - 'sessionrestore_no_auto_restore', 15:50:30 INFO - 'tabpaint']}, 15:50:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:50:30 INFO - 'tests': ['a11yr', 15:50:30 INFO - 'ts_paint', 15:50:30 INFO - 'tpaint', 15:50:30 INFO - 'sessionrestore', 15:50:30 INFO - 'sessionrestore_no_auto_restore', 15:50:30 INFO - 'tabpaint']}, 15:50:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:50:30 INFO - 'tests': ['tsvgx', 15:50:30 INFO - 'tsvgr_opacity', 15:50:30 INFO - 'tart', 15:50:30 INFO - 'tscrollx', 15:50:30 INFO - 'cart']}, 15:50:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:50:30 INFO - 'tsvgr_opacity', 15:50:30 INFO - 'tart', 15:50:30 INFO - 'tscrollx', 15:50:30 INFO - 'cart']}, 15:50:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:30 INFO - 'talos_options': ['--disable-e10s'], 15:50:30 INFO - 'tests': ['tp5o']}, 15:50:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:30 INFO - 'tests': ['tp5o']}, 15:50:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:30 INFO - 'talos_options': ['--disable-e10s', 15:50:30 INFO - '--xperf_path', 15:50:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:30 INFO - 'tests': ['tp5n']}, 15:50:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:50:30 INFO - 'talos_options': ['--xperf_path', 15:50:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:50:30 INFO - 'tests': ['tp5n']}}, 15:50:30 INFO - 'talos.zip': {'path': '', 15:50:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:50:30 INFO - Running post-action listener: _resource_record_post_action 15:50:30 INFO - [mozharness: 2016-09-12 22:50:30.657478Z] Finished populate-webroot step (success) 15:50:30 INFO - [mozharness: 2016-09-12 22:50:30.657568Z] Running create-virtualenv step. 15:50:30 INFO - Running pre-action listener: _resource_record_pre_action 15:50:30 INFO - Running main action method: create_virtualenv 15:50:30 INFO - Creating virtualenv /builds/slave/test/build/venv 15:50:30 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 15:50:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:50:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:50:30 INFO - Using real prefix '/usr' 15:50:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:50:31 INFO - Installing distribute.............................................................................................................................................................................................done. 15:50:33 INFO - Installing pip.................done. 15:50:33 INFO - Return code: 0 15:50:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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')]} 15:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:33 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 0xe7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1140180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e3b0>, '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 0x113f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x113f740>, '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': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', '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 15:50:33 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 15:50:33 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 15:50:33 INFO - Using env: {'DISPLAY': ':0', 15:50:33 INFO - 'HOME': '/home/cltbld', 15:50:33 INFO - 'LANG': 'en_US.UTF-8', 15:50:33 INFO - 'LANGUAGE': 'en_US:en', 15:50:33 INFO - 'LOGNAME': 'cltbld', 15:50:33 INFO - 'MAIL': '/var/mail/cltbld', 15:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:33 INFO - 'MOZ_NO_REMOTE': '1', 15:50:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:33 INFO - 'NO_EM_RESTART': '1', 15:50:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:33 INFO - 'PWD': '/builds/slave/test', 15:50:33 INFO - 'SHELL': '/bin/bash', 15:50:33 INFO - 'SHLVL': '1', 15:50:33 INFO - 'TERM': 'linux', 15:50:33 INFO - 'TMOUT': '86400', 15:50:33 INFO - 'USER': 'cltbld', 15:50:33 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', 15:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:33 INFO - '_': '/tools/buildbot/bin/python'} 15:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:33 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 15:50:33 INFO - Cleaning up... 15:50:33 INFO - Return code: 0 15:50:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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')]} 15:50:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:33 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 0xe7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1140180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e3b0>, '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 0x113f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x113f740>, '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': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', '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 15:50:33 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 15:50:33 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 15:50:33 INFO - Using env: {'DISPLAY': ':0', 15:50:33 INFO - 'HOME': '/home/cltbld', 15:50:33 INFO - 'LANG': 'en_US.UTF-8', 15:50:33 INFO - 'LANGUAGE': 'en_US:en', 15:50:33 INFO - 'LOGNAME': 'cltbld', 15:50:33 INFO - 'MAIL': '/var/mail/cltbld', 15:50:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:33 INFO - 'MOZ_NO_REMOTE': '1', 15:50:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:33 INFO - 'NO_EM_RESTART': '1', 15:50:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:33 INFO - 'PWD': '/builds/slave/test', 15:50:33 INFO - 'SHELL': '/bin/bash', 15:50:33 INFO - 'SHLVL': '1', 15:50:33 INFO - 'TERM': 'linux', 15:50:33 INFO - 'TMOUT': '86400', 15:50:33 INFO - 'USER': 'cltbld', 15:50:33 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', 15:50:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:33 INFO - '_': '/tools/buildbot/bin/python'} 15:50:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:33 INFO - Downloading/unpacking psutil>=3.1.1 15:50:33 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 15:50:33 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 15:50:33 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 15:50:33 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 15:50:33 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 15:50:33 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 15:50:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:50:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:35 INFO - Installing collected packages: psutil 15:50:35 INFO - Running setup.py install for psutil 15:50:35 INFO - building 'psutil._psutil_linux' extension 15:50:35 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 15:50:35 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 15:50:35 INFO - building 'psutil._psutil_posix' extension 15:50:35 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 15:50:36 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 15:50:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:50:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:50:36 INFO - Successfully installed psutil 15:50:36 INFO - Cleaning up... 15:50:36 INFO - Return code: 0 15:50:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:50:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:50:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:36 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 0xe7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1140180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e3b0>, '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 0x113f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x113f740>, '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': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', '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 15:50:36 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 15:50:36 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 15:50:36 INFO - Using env: {'DISPLAY': ':0', 15:50:36 INFO - 'HOME': '/home/cltbld', 15:50:36 INFO - 'LANG': 'en_US.UTF-8', 15:50:36 INFO - 'LANGUAGE': 'en_US:en', 15:50:36 INFO - 'LOGNAME': 'cltbld', 15:50:36 INFO - 'MAIL': '/var/mail/cltbld', 15:50:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:36 INFO - 'MOZ_NO_REMOTE': '1', 15:50:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:36 INFO - 'NO_EM_RESTART': '1', 15:50:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:36 INFO - 'PWD': '/builds/slave/test', 15:50:36 INFO - 'SHELL': '/bin/bash', 15:50:36 INFO - 'SHLVL': '1', 15:50:36 INFO - 'TERM': 'linux', 15:50:36 INFO - 'TMOUT': '86400', 15:50:36 INFO - 'USER': 'cltbld', 15:50:36 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', 15:50:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:36 INFO - '_': '/tools/buildbot/bin/python'} 15:50:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:50:36 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 15:50:36 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 15:50:36 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 15:50:36 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 15:50:36 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 15:50:36 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 15:50:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:50:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:50:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:50:38 INFO - Installing collected packages: mozsystemmonitor 15:50:38 INFO - Running setup.py install for mozsystemmonitor 15:50:38 INFO - Successfully installed mozsystemmonitor 15:50:38 INFO - Cleaning up... 15:50:38 INFO - Return code: 0 15:50:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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')]} 15:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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', '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 0xe7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1140180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e3b0>, '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 0x113f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x113f740>, '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': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', '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 15:50: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:50: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 jsonschema==2.5.1 15:50:38 INFO - Using env: {'DISPLAY': ':0', 15:50:38 INFO - 'HOME': '/home/cltbld', 15:50:38 INFO - 'LANG': 'en_US.UTF-8', 15:50:38 INFO - 'LANGUAGE': 'en_US:en', 15:50:38 INFO - 'LOGNAME': 'cltbld', 15:50:38 INFO - 'MAIL': '/var/mail/cltbld', 15:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:38 INFO - 'MOZ_NO_REMOTE': '1', 15:50:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:38 INFO - 'NO_EM_RESTART': '1', 15:50:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:38 INFO - 'PWD': '/builds/slave/test', 15:50:38 INFO - 'SHELL': '/bin/bash', 15:50:38 INFO - 'SHLVL': '1', 15:50:38 INFO - 'TERM': 'linux', 15:50:38 INFO - 'TMOUT': '86400', 15:50:38 INFO - 'USER': 'cltbld', 15:50:38 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', 15:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:38 INFO - '_': '/tools/buildbot/bin/python'} 15:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:38 INFO - Downloading/unpacking jsonschema==2.5.1 15:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:50:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:50:40 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 15:50:40 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 15:50:40 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 15:50:40 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 15:50:40 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 15:50:40 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 15:50:40 INFO - Downloading functools32-3.2.3-2.tar.gz 15:50:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:50:40 INFO - warning: no files found matching '*.txt' 15:50:40 INFO - no previously-included directories found matching 'build' 15:50:40 INFO - no previously-included directories found matching 'dist' 15:50:40 INFO - no previously-included directories found matching '.git*' 15:50:40 INFO - Installing collected packages: jsonschema, functools32 15:50:40 INFO - Running setup.py install for functools32 15:50:40 INFO - warning: no files found matching '*.txt' 15:50:40 INFO - no previously-included directories found matching 'build' 15:50:40 INFO - no previously-included directories found matching 'dist' 15:50:40 INFO - no previously-included directories found matching '.git*' 15:50:40 INFO - Successfully installed jsonschema functools32 15:50:40 INFO - Cleaning up... 15:50:41 INFO - Return code: 0 15:50:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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')]} 15:50:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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', '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 0xe7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1140180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e3b0>, '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 0x113f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x113f740>, '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': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', '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 15:50: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:50: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 functools32==3.2.3-2 15:50:41 INFO - Using env: {'DISPLAY': ':0', 15:50:41 INFO - 'HOME': '/home/cltbld', 15:50:41 INFO - 'LANG': 'en_US.UTF-8', 15:50:41 INFO - 'LANGUAGE': 'en_US:en', 15:50:41 INFO - 'LOGNAME': 'cltbld', 15:50:41 INFO - 'MAIL': '/var/mail/cltbld', 15:50:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:41 INFO - 'MOZ_NO_REMOTE': '1', 15:50:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:41 INFO - 'NO_EM_RESTART': '1', 15:50:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:41 INFO - 'PWD': '/builds/slave/test', 15:50:41 INFO - 'SHELL': '/bin/bash', 15:50:41 INFO - 'SHLVL': '1', 15:50:41 INFO - 'TERM': 'linux', 15:50:41 INFO - 'TMOUT': '86400', 15:50:41 INFO - 'USER': 'cltbld', 15:50:41 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', 15:50:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:41 INFO - '_': '/tools/buildbot/bin/python'} 15:50:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:50:41 INFO - Cleaning up... 15:50:41 INFO - Return code: 0 15:50:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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')]} 15:50:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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', '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 0xe7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1140180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e3b0>, '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 0x113f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x113f740>, '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': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', '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 15:50: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:50: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 blobuploader==1.2.4 15:50:41 INFO - Using env: {'DISPLAY': ':0', 15:50:41 INFO - 'HOME': '/home/cltbld', 15:50:41 INFO - 'LANG': 'en_US.UTF-8', 15:50:41 INFO - 'LANGUAGE': 'en_US:en', 15:50:41 INFO - 'LOGNAME': 'cltbld', 15:50:41 INFO - 'MAIL': '/var/mail/cltbld', 15:50:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:41 INFO - 'MOZ_NO_REMOTE': '1', 15:50:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:41 INFO - 'NO_EM_RESTART': '1', 15:50:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:41 INFO - 'PWD': '/builds/slave/test', 15:50:41 INFO - 'SHELL': '/bin/bash', 15:50:41 INFO - 'SHLVL': '1', 15:50:41 INFO - 'TERM': 'linux', 15:50:41 INFO - 'TMOUT': '86400', 15:50:41 INFO - 'USER': 'cltbld', 15:50:41 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', 15:50:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:41 INFO - '_': '/tools/buildbot/bin/python'} 15:50:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:41 INFO - Downloading/unpacking blobuploader==1.2.4 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50:43 INFO - Downloading blobuploader-1.2.4.tar.gz 15:50:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:50:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:50:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:50:44 INFO - Downloading docopt-0.6.1.tar.gz 15:50:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:50:44 INFO - Installing collected packages: blobuploader, requests, docopt 15:50:44 INFO - Running setup.py install for blobuploader 15:50:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:50:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:50:44 INFO - Running setup.py install for requests 15:50:44 INFO - Running setup.py install for docopt 15:50:44 INFO - Successfully installed blobuploader requests docopt 15:50:44 INFO - Cleaning up... 15:50:44 INFO - Return code: 0 15:50:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50: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')]} 15:50:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50: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')]} 15:50:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:44 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 0xe7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1140180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e3b0>, '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 0x113f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x113f740>, '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': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', '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 15:50:44 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 15:50:44 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 15:50:44 INFO - Using env: {'DISPLAY': ':0', 15:50:44 INFO - 'HOME': '/home/cltbld', 15:50:44 INFO - 'LANG': 'en_US.UTF-8', 15:50:44 INFO - 'LANGUAGE': 'en_US:en', 15:50:44 INFO - 'LOGNAME': 'cltbld', 15:50:44 INFO - 'MAIL': '/var/mail/cltbld', 15:50:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:44 INFO - 'MOZ_NO_REMOTE': '1', 15:50:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:44 INFO - 'NO_EM_RESTART': '1', 15:50:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:44 INFO - 'PWD': '/builds/slave/test', 15:50:44 INFO - 'SHELL': '/bin/bash', 15:50:44 INFO - 'SHLVL': '1', 15:50:44 INFO - 'TERM': 'linux', 15:50:44 INFO - 'TMOUT': '86400', 15:50:44 INFO - 'USER': 'cltbld', 15:50:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', 15:50:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:44 INFO - '_': '/tools/buildbot/bin/python'} 15:50:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:44 INFO - Running setup.py (path:/tmp/pip-TsUKvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:44 INFO - Running setup.py (path:/tmp/pip-byJdlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:45 INFO - Running setup.py (path:/tmp/pip-9SOlhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:45 INFO - Running setup.py (path:/tmp/pip-3BhwMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:45 INFO - Running setup.py (path:/tmp/pip-XnaMCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:45 INFO - Running setup.py (path:/tmp/pip-WbMVo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:45 INFO - Running setup.py (path:/tmp/pip-0Ki1AC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:45 INFO - Running setup.py (path:/tmp/pip-yWE2Uu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:45 INFO - Running setup.py (path:/tmp/pip-Uai5Cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:45 INFO - Running setup.py (path:/tmp/pip-17EoFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:45 INFO - Running setup.py (path:/tmp/pip-X4039z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:45 INFO - Running setup.py (path:/tmp/pip-VrI_0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:45 INFO - Running setup.py (path:/tmp/pip-pulGvr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:45 INFO - Running setup.py (path:/tmp/pip-E4xA4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:45 INFO - Running setup.py (path:/tmp/pip-82C4X_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:46 INFO - Running setup.py (path:/tmp/pip-Zuu8aR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:46 INFO - Running setup.py (path:/tmp/pip-_93vTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:50:46 INFO - Running setup.py install for manifestparser 15:50:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:50:46 INFO - Running setup.py install for mozcrash 15:50:46 INFO - Running setup.py install for mozdebug 15:50:46 INFO - Running setup.py install for mozdevice 15:50:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:50:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:50:46 INFO - Running setup.py install for mozfile 15:50:46 INFO - Running setup.py install for mozhttpd 15:50:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:50:46 INFO - Running setup.py install for mozinfo 15:50:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:50:46 INFO - Running setup.py install for mozInstall 15:50:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:50:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:50:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:50:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:50:46 INFO - Running setup.py install for mozleak 15:50:47 INFO - Running setup.py install for mozlog 15:50:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:50:47 INFO - Running setup.py install for moznetwork 15:50:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:50:47 INFO - Running setup.py install for mozprocess 15:50:47 INFO - Running setup.py install for mozprofile 15:50:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:50:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:50:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:50:47 INFO - Running setup.py install for mozrunner 15:50:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:50:47 INFO - Running setup.py install for mozscreenshot 15:50:47 INFO - Running setup.py install for moztest 15:50:47 INFO - Running setup.py install for mozversion 15:50:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:50:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:50:47 INFO - Cleaning up... 15:50:47 INFO - Return code: 0 15:50:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:47 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')]} 15:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:47 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')]} 15:50:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:47 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 0xe7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1140180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e3b0>, '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 0x113f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x113f740>, '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': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', '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 15:50:47 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 15:50:47 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 15:50:47 INFO - Using env: {'DISPLAY': ':0', 15:50:47 INFO - 'HOME': '/home/cltbld', 15:50:47 INFO - 'LANG': 'en_US.UTF-8', 15:50:47 INFO - 'LANGUAGE': 'en_US:en', 15:50:47 INFO - 'LOGNAME': 'cltbld', 15:50:47 INFO - 'MAIL': '/var/mail/cltbld', 15:50:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:47 INFO - 'MOZ_NO_REMOTE': '1', 15:50:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:47 INFO - 'NO_EM_RESTART': '1', 15:50:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:47 INFO - 'PWD': '/builds/slave/test', 15:50:47 INFO - 'SHELL': '/bin/bash', 15:50:47 INFO - 'SHLVL': '1', 15:50:47 INFO - 'TERM': 'linux', 15:50:47 INFO - 'TMOUT': '86400', 15:50:47 INFO - 'USER': 'cltbld', 15:50:47 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', 15:50:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:47 INFO - '_': '/tools/buildbot/bin/python'} 15:50:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:50:48 INFO - Running setup.py (path:/tmp/pip-cpVnPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:50:48 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)) 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:50:48 INFO - Running setup.py (path:/tmp/pip-4g6lW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:50:48 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)) 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:50:48 INFO - Running setup.py (path:/tmp/pip-xEQCCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:50:48 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)) 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:50:48 INFO - Running setup.py (path:/tmp/pip-DNf5lV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:50:48 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)) 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:50:48 INFO - Running setup.py (path:/tmp/pip-aINqSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:50:48 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)) 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:50:48 INFO - Running setup.py (path:/tmp/pip-M5V2vt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:50:48 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)) 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:50:48 INFO - Running setup.py (path:/tmp/pip-p3ulfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:50:48 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)) 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:50:48 INFO - Running setup.py (path:/tmp/pip-W4Wpa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:50:48 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)) 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:50:48 INFO - Running setup.py (path:/tmp/pip-pARgTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:50:48 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)) 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:50:48 INFO - Running setup.py (path:/tmp/pip-sw4uqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:50:48 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)) 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:50:48 INFO - Running setup.py (path:/tmp/pip-JXn9TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:50:48 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)) 15:50:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:50:48 INFO - Running setup.py (path:/tmp/pip-CcPHrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:50:49 INFO - Running setup.py (path:/tmp/pip-N95qd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:50:49 INFO - Running setup.py (path:/tmp/pip-fZjBZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:49 INFO - Running setup.py (path:/tmp/pip-NgJ9OX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:50:49 INFO - Running setup.py (path:/tmp/pip-bai7t2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:50:49 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)) 15:50:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:50:49 INFO - Running setup.py (path:/tmp/pip-jDtJIj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:50:49 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)) 15:50:49 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)) 15:50:49 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)) 15:50:49 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)) 15:50:49 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)) 15:50:49 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)) 15:50:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50: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 15:50:51 INFO - Downloading blessings-1.6.tar.gz 15:50:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:50:51 INFO - Installing collected packages: blessings 15:50:51 INFO - Running setup.py install for blessings 15:50:51 INFO - Successfully installed blessings 15:50:51 INFO - Cleaning up... 15:50:51 INFO - Return code: 0 15:50:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:50:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:51 INFO - Reading from file tmpfile_stdout 15:50:51 INFO - Current package versions: 15:50:51 INFO - argparse == 1.2.1 15:50:51 INFO - blessings == 1.6 15:50:51 INFO - blobuploader == 1.2.4 15:50:51 INFO - docopt == 0.6.1 15:50:51 INFO - functools32 == 3.2.3-2 15:50:51 INFO - jsonschema == 2.5.1 15:50:51 INFO - manifestparser == 1.1 15:50:51 INFO - mozInstall == 1.12 15:50:51 INFO - mozcrash == 0.17 15:50:51 INFO - mozdebug == 0.1 15:50:51 INFO - mozdevice == 0.48 15:50:51 INFO - mozfile == 1.2 15:50:51 INFO - mozhttpd == 0.7 15:50:51 INFO - mozinfo == 0.9 15:50:51 INFO - mozleak == 0.1 15:50:51 INFO - mozlog == 3.3 15:50:51 INFO - moznetwork == 0.27 15:50:51 INFO - mozprocess == 0.23 15:50:51 INFO - mozprofile == 0.28 15:50:51 INFO - mozrunner == 6.12 15:50:51 INFO - mozscreenshot == 0.1 15:50:51 INFO - mozsystemmonitor == 0.3 15:50:51 INFO - moztest == 0.7 15:50:51 INFO - mozversion == 1.4 15:50:51 INFO - psutil == 3.1.1 15:50:51 INFO - requests == 1.2.3 15:50:51 INFO - wsgiref == 0.1.2 15:50:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:50:51 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')]} 15:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:51 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')]} 15:50:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:51 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 0xe7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1140180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e3b0>, '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 0x113f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x113f740>, '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': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', '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 15:50:51 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 15:50:51 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 15:50:51 INFO - Using env: {'DISPLAY': ':0', 15:50:51 INFO - 'HOME': '/home/cltbld', 15:50:51 INFO - 'LANG': 'en_US.UTF-8', 15:50:51 INFO - 'LANGUAGE': 'en_US:en', 15:50:51 INFO - 'LOGNAME': 'cltbld', 15:50:51 INFO - 'MAIL': '/var/mail/cltbld', 15:50:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:51 INFO - 'MOZ_NO_REMOTE': '1', 15:50:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:51 INFO - 'NO_EM_RESTART': '1', 15:50:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:51 INFO - 'PWD': '/builds/slave/test', 15:50:51 INFO - 'SHELL': '/bin/bash', 15:50:51 INFO - 'SHLVL': '1', 15:50:51 INFO - 'TERM': 'linux', 15:50:51 INFO - 'TMOUT': '86400', 15:50:51 INFO - 'USER': 'cltbld', 15:50:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', 15:50:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:51 INFO - '_': '/tools/buildbot/bin/python'} 15:50:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:51 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)) 15:50:51 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)) 15:50:51 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)) 15:50:51 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)) 15:50:51 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)) 15:50:51 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)) 15:50:51 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)) 15:50:51 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)) 15:50:51 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)) 15:50:51 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)) 15:50:51 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)) 15:50:51 INFO - Cleaning up... 15:50:51 INFO - Return code: 0 15:50:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:50:51 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')]} 15:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:50:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:51 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')]} 15:50:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:50:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:50:51 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 0xe7f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfe28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1140180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x113e3b0>, '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 0x113f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x113f740>, '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': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', '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 15:50:51 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 15:50:51 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 15:50:51 INFO - Using env: {'DISPLAY': ':0', 15:50:51 INFO - 'HOME': '/home/cltbld', 15:50:51 INFO - 'LANG': 'en_US.UTF-8', 15:50:51 INFO - 'LANGUAGE': 'en_US:en', 15:50:51 INFO - 'LOGNAME': 'cltbld', 15:50:51 INFO - 'MAIL': '/var/mail/cltbld', 15:50:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:50:51 INFO - 'MOZ_NO_REMOTE': '1', 15:50:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:50:51 INFO - 'NO_EM_RESTART': '1', 15:50:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:50:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:50:51 INFO - 'PWD': '/builds/slave/test', 15:50:51 INFO - 'SHELL': '/bin/bash', 15:50:51 INFO - 'SHLVL': '1', 15:50:51 INFO - 'TERM': 'linux', 15:50:51 INFO - 'TMOUT': '86400', 15:50:51 INFO - 'USER': 'cltbld', 15:50:51 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', 15:50:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:50:51 INFO - '_': '/tools/buildbot/bin/python'} 15:50:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:50:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:50:52 INFO - Cleaning up... 15:50:52 INFO - Return code: 0 15:50:52 INFO - Running post-action listener: _resource_record_post_action 15:50:52 INFO - Running post-action listener: _start_resource_monitoring 15:50:52 INFO - Starting resource monitoring. 15:50:52 INFO - [mozharness: 2016-09-12 22:50:52.219887Z] Finished create-virtualenv step (success) 15:50:52 INFO - [mozharness: 2016-09-12 22:50:52.220317Z] Running install step. 15:50:52 INFO - Running pre-action listener: _resource_record_pre_action 15:50:52 INFO - Running main action method: install 15:50:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:50:52 INFO - Reading from file tmpfile_stdout 15:50:52 INFO - Detecting whether we're running mozinstall >=1.0... 15:50:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:50:52 INFO - Reading from file tmpfile_stdout 15:50:52 INFO - Output received: 15:50:52 INFO - Usage: mozinstall [options] installer 15:50:52 INFO - Options: 15:50:52 INFO - -h, --help show this help message and exit 15:50:52 INFO - -d DEST, --destination=DEST 15:50:52 INFO - Directory to install application into. [default: 15:50:52 INFO - "/builds/slave/test"] 15:50:52 INFO - --app=APP Application being installed. [default: firefox] 15:50:52 INFO - mkdir: /builds/slave/test/build/application 15:50:52 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'] 15:50:52 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 15:51:09 INFO - Reading from file tmpfile_stdout 15:51:09 INFO - Output received: 15:51:09 INFO - /builds/slave/test/build/application/firefox/firefox 15:51:09 INFO - Running post-action listener: _resource_record_post_action 15:51:09 INFO - [mozharness: 2016-09-12 22:51:09.946871Z] Finished install step (success) 15:51:09 INFO - [mozharness: 2016-09-12 22:51:09.947143Z] Running run-tests step. 15:51:09 INFO - Running pre-action listener: _resource_record_pre_action 15:51:09 INFO - Running main action method: run_tests 15:51:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:51:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:51:09 INFO - Python 2.7.3 15:51:09 INFO - Return code: 0 15:51:09 INFO - grabbing minidump binary from tooltool 15:51:09 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')]} 15:51:09 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 0x113e3b0>, '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 0x113f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x113f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:51:09 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 15:51:09 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 15:51:09 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 15:51:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:51:10 INFO - Return code: 0 15:51:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:51:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:51:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:51:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:51:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:51:10 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/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 15:51:10 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/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-038 --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 15:51:10 INFO - Using env: {'DISPLAY': ':0', 15:51:10 INFO - 'HOME': '/home/cltbld', 15:51:10 INFO - 'LANG': 'en_US.UTF-8', 15:51:10 INFO - 'LANGUAGE': 'en_US:en', 15:51:10 INFO - 'LOGNAME': 'cltbld', 15:51:10 INFO - 'MAIL': '/var/mail/cltbld', 15:51:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:51:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:51:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:10 INFO - 'MOZ_NO_REMOTE': '1', 15:51:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:51:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:51:10 INFO - 'NO_EM_RESTART': '1', 15:51:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:51:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:10 INFO - 'PWD': '/builds/slave/test', 15:51:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:51:10 INFO - 'SHELL': '/bin/bash', 15:51:10 INFO - 'SHLVL': '1', 15:51:10 INFO - 'TERM': 'linux', 15:51:10 INFO - 'TMOUT': '86400', 15:51:10 INFO - 'USER': 'cltbld', 15:51:10 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695', 15:51:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:10 INFO - '_': '/tools/buildbot/bin/python'} 15:51:10 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/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--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 15:51:10 INFO - mozversion application_buildid: 20160912133916 15:51:10 INFO - mozversion application_changeset: cdf6988ace7810606807d1a1d73cfb06b08dc93a 15:51:10 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:51:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:51:10 INFO - mozversion application_name: Firefox 15:51:10 INFO - mozversion application_remotingname: firefox-dev 15:51:10 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:51:10 INFO - mozversion application_vendor: Mozilla 15:51:10 INFO - mozversion application_version: 50.0a2 15:51:10 INFO - mozversion platform_buildid: 20160912133916 15:51:10 INFO - mozversion platform_changeset: cdf6988ace7810606807d1a1d73cfb06b08dc93a 15:51:10 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:51:10 INFO - mozversion platform_version: 50.0a2 15:51:10 INFO - using testdate: 1473720670 15:51:10 INFO - actual date: 1473720670 15:51:10 INFO - starting webserver on 'localhost:33977' 15:51:10 INFO - SUITE-START | Running 2 tests 15:51:10 INFO - TEST-START | dromaeo_css 15:51:10 INFO - operating with platform_type : linux_ 15:51:10 INFO - Initialising browser for dromaeo_css test... 15:51:10 INFO - TEST-INFO | started process 23152 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoUIZq5/profile http://localhost:33977/getInfo.html) 15:51:14 INFO - PROCESS | 23152 | __metrics Screen width/height:1600/1200 15:51:14 INFO - PROCESS | 23152 | colorDepth:24 15:51:14 INFO - PROCESS | 23152 | Browser inner width/height: 1024/697 15:51:14 INFO - PROCESS | 23152 | __metrics 15:51:15 INFO - PROCESS | 23152 | 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" 15:51:15 INFO - PROCESS | 23152 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:51:16 INFO - TEST-INFO | 23152: exit 0 15:51:16 INFO - Browser initialized. 15:51:16 INFO - Running cycle 1/1 for dromaeo_css test... 15:51:16 INFO - TEST-INFO | started process 23245 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoUIZq5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:51:28 INFO - PROCESS | 23245 | RSS: Main: 146378752 15:51:28 INFO - PROCESS | 23245 | 15:52:19 INFO - PROCESS | 23245 | 1473720739532 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] 15:54:27 INFO - PROCESS | 23245 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33977/tests/dromaeo/cssquery-ext.html) 15:54:29 INFO - PROCESS | 23245 | RSS: Main: 259481600 15:54:29 INFO - PROCESS | 23245 | 15:57:31 INFO - PROCESS | 23245 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-ext.html (next: http://localhost:33977/tests/dromaeo/cssquery-jquery.html) 15:57:32 INFO - PROCESS | 23245 | RSS: Main: 224583680 15:57:32 INFO - PROCESS | 23245 | 16:00:33 INFO - PROCESS | 23245 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33977/tests/dromaeo/cssquery-mootools.html) 16:00:34 INFO - PROCESS | 23245 | RSS: Main: 206073856 16:00:34 INFO - PROCESS | 23245 | 16:03:36 INFO - PROCESS | 23245 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33977/tests/dromaeo/cssquery-prototype.html) 16:03:36 INFO - PROCESS | 23245 | RSS: Main: 175235072 16:03:36 INFO - PROCESS | 23245 | 16:06:39 INFO - PROCESS | 23245 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33977/tests/dromaeo/cssquery-yui.html) 16:06:40 INFO - PROCESS | 23245 | RSS: Main: 175607808 16:06:40 INFO - PROCESS | 23245 | 16:09:42 INFO - PROCESS | 23245 | Cycle 1(1): loaded http://localhost:33977/tests/dromaeo/cssquery-yui.html (next: http://localhost:33977/tests/dromaeo/cssquery-dojo.html) 16:09:43 INFO - PROCESS | 23245 | RSS: Main: 250896384 16:09:43 INFO - PROCESS | 23245 | 16:09:43 INFO - PROCESS | 23245 | __start_tp_report 16:09:43 INFO - PROCESS | 23245 | _x_x_mozilla_page_load 16:09:43 INFO - PROCESS | 23245 | _x_x_mozilla_page_load_details 16:09:43 INFO - PROCESS | 23245 | |i|pagename|runs| 16:09:43 INFO - PROCESS | 23245 | |0;dojo.html;3101.9844900775493;3106.394253120641;3128.953065704015;3153.7477001839857;3160.2099475131217;5414.377346605142;5525.889482210358;5526.668399896009;5550.8057217997375;5552.194931734906;5517.972410137953;5543.61194716369;5543.695096769676;5548.112302031675;5557.138643510252;4897.583705385046;5017.899642007167;5040.571551418134;5087.618428617869;5101.948980510203;4859.562639362457;4978.900421991558;4983.352164218664;4998.10034193845;5000.849974500771;5106.5659418949435;5121.12940800063;5121.308623335858;5124;5145.176771716526;5104.336436263299;5106.234064890259;5107.770150343206;5114.411842638107;5135.948640513584;4856.490068542787;4913.336699545552;4957.801687932479;5017.8243761468175;5022.121128802445;5457.590680698963;5463.07127788273;5466.781328746885;5473.507384335429;5536.390996990328;5802.448767367136;5855.8536036599;5862.882742345172;5871.6183448075735;5889.116632505158;5671.8582035449035;5799.3040835100055;5850.76596936122;5858.384869588717;5912.852178695524;5526.06056970316;5541.5843811714285;5544.002079625666;5562;5568.081266591008;6007.429294217042;6042.728077236535;6044.848878778066;6048.999999999912;6054.334023257438;5520.392756796749;5666.914996275059;5794.739236734357;5800.187973683687;5804;5798.82603521895;5868.618539794899;5875.9118613220835;5908.822735317947;5910.556708246899;5803.332616749043;5848.619839710405;5856.94143058564;5859.1797148399255;5865.970670146708;5647.887042259132;5684.459976302244;5782.392848750905;5862.296524417097;5878.265216847894;4445.26653102241;4451.821927122944;4455.509893911667;4467.821287148543;4537.38745269391;4012.719109662354;4014.217227640582;4015.8996025099605;4031.5162180538523;4046.2312160689376;5550.333959924754;5553.416891226444;5558.527525160326;5560.221568980346;5585.050541407971;5673.375928647845;5776.971115144397;5797.36229014808;5819.650820950672;5918.141869428909;5598.468145526169;5604.355499117653;5606.579506537026;5607.243022192033;5629.099344105005;4943.060818444484;4986;4990.825321113816;4991.351124353883;5028.673136246132;5866.7066646668345;5993.940060599338;6009.399060093956;6039.879202416015;6139.952147135068;5891.734871930687;5923.614965027259;5923.881522369442;5926.822195334147;5948.018576934757;6085.056816193497;6096.512279017579;6114.205153330038;6131.202943617302;6141.477974372228;5552.278203833475;5755.942440575541;5771.307443106854;5787.6816775076095;5804.216430781959;5839.591228613956;5896.616719913192;5896.970515147397;5898.911516327173;5899.026660601125;5770.076787713946;5772.509336706427;5772.913406298825;5775.48020678141;5780.30636323627;5528;5603.663780173202;5635.267415235993;5656.462636049569;5781.161731548986;5693.373728889902;5708.743106560131;5709.628874123168;5711.171880077449;5719.456651618089;5722.942770572241;5729.226554415267;5738.770449181986;5744.425557444222;5744.655320680772;5382.461753824587;5480.835574932758;5531.557475402039;5551.916721249104;5567.164925261081 16:09:43 INFO - PROCESS | 23245 | |1;ext.html;589.7287247865981;621.3506885304856;621.8445388652837;646.9644169570672;653.5653790229496;36345;46949.76525117374;47990.280145797755;48023.27965080522;48055.279170812464;49534.25698614515;49914;49947.25079123816;50008.49991500084;50015;8578.39951203416;9369.625214991393;9506.857397139049;9534;11284.379359135244;10981.17641176915;11437.599684011062;11439.656810295704;11442.313461192354;11442.656720298362;11412.087033037336;11434.142439317044;11488.827667584992;11493.942530287337;11496.022838058774;8677.69628063021;11131.165162612837;11401.087912967027;11432.942835285854;11483.942580287045;11246.98777110056;11337;11343.546258149747;11346.262492938014;11353.20527563071;46529.76735116337;46823.765881170715;46912.29631555529;46935.765321173516;47006.52993470056;3773.1887644156363;3782.6973842092566;3838.193979264358;3841.2125514269787;3996.5603783583783;36311.27377452463;36606.63393366059;36802;36880.63119368799;37190.4421433676;4887.926681099786;4926.3842019747535;4963.478834722375;5029.949700503022;5167;384157;385712;386999;387366.9999999972;387476;38573;39137.2172556553;39700.2059958805;41126.58873411288;65778.67110664416;41307.7934610325;41443.37834932478;41484.792576036925;41567.1686566267;41655.1668966625;17405.303787848345;17508;17555.912220438815;17571.209295581728;17571.385001524886;13315.66710832237;13655.658608534866;14324.713505729827;14845.109293442429;14902;9302.069793020779;9315.813683726288;9320.766980825534;9377.718668439958;9391.154796068387;3425.246445781924;3499.232887391851;3506.1760486285702;3537.557805274341;3572.4105522589;1141.315210873486;1182.9704257393635;1186.6262127429832;1197.8921897029309;1210.7881120803825;32953.67046329554;34537.136571585914;34559.4816077759;35227.295454090774;35227.64772352295;1162.581470670558;1194.4565222823594;1207.6497815633368;1212.4483360071147;1255.4978008796554;18334.633307333777;18361.540961475803;18475;18531.536711582048;18654.34709785178;489951;492534;494556;494826;496861;62312.37687623066;62909.685451573365;63173;63211.367886321466;63311.6834415825;1274.3055035005943;1407.2119613016744;1418.546065259107;1423.4590855474853;1441.2721575604044;35203.11992200215;37572.436413454365;38124.2375152501;38167.80916095513;38246.42630360491;56489.15266270927;57157.142642861225;57164.42835571591;57164.71417642885;57205.42794572001;478668;478830;479940;480540;480989;31114.377712446076;32090.679093208768;32427.18932026718;33586;33593.66406335905;54169.187462187314;54343.18485222804;54523.72738136284;54666.18000730072;54689.453105468434;43475.56524434715;44738.28487126058;45786.31320530261;46723;47352.28971565498;44287.778561106985;45708;46327.305090423;46408;47525.76237118792;45753;46532.7673361631;46962.530374697184;47125.52874471348;47310;37488;39946.800265998485;40429.79785101055;40867.795661021504;41302.586974129874;29981;30923.44380396193;31212.375752484368;31407.371852562363;31723.365532688757 16:09:43 INFO - PROCESS | 23245 | |2;jquery.html;397.884613462096;402.39640539191214;428.712762449159;431.2474731593369;442.89813342931126;18357.632847343062;20159.798402015975;20518.076686549102;20636.690449643247;20749.896250518763;19812.70280945787;19872.40382788515;20457.693134602992;20534.075966581502;20571.794282057173;5595.160725891108;5964.194833697438;6005.12925625784;6016.849578760544;6016.939830601704;4447.11057788442;5891.293044834626;6352.904706429407;6626.370494802986;6666.600003999752;6508.8047358579315;6727.629980351103;6736.2590115087305;6742.932570674279;6743.02226177204;6697.799066028027;6733.528652994292;6803.217629972542;6844.965775171092;6849.863002739918;5833.679147646878;6026.608270462406;6056.6063205891915;6068.635881847059;6068.726907289182;25377.365565861;25454.745452545423;25617.871910640326;25858.482830343477;25863.61204581933;2540.8221424500293;2652.310399296197;2658.3088397016913;2674.71915448877;3194.361127774431;10839.349639021606;11093.112550995982;11241.325520468798;11443;11494.885051149466;2005.0977060322766;2072.5544008038346;2085.9165633374646;2094.2984100326407;2099.611571859211;88323.55838220831;90427;90675;91987.08012919853;94538.52730736302;11419.714507137222;11592.594259200887;11786.410679466164;11936.761264774656;12016.279023258632;16698.4155554555;16707.91646041762;16742.24659890308;16761.7485737714;16763.916180419263;8980.910190898137;9030.954845225731;9047.276217902552;9106.726798195932;9121.77195570116;8897.777555561162;8979.46123232608;8982.191602755784;9166.450012999241;9189.63241470347;4953.18272485036;5000.274960130833;5025.824096156617;5101.591872650179;5131.7690703918415;2024.5545979884403;2052.332991777678;2066.6176757299645;2067.173130747713;2069.9482512937298;1007.4257673126394;1015.2690063154517;1025.3130402630256;1036.2797855490362;1112.593903225317;19982.800171998384;21615.78384216169;21744.238951636617;21854.562908741736;22008.44978875509;1013.8225810483136;1038.4911393417322;1040.256216804988;1042.697617690861;1067.5783065689166;11001.284916480403;12054.879451205552;12060.819087713691;12126;12166.330851803235;615875;635803;639423;643666;647848;63088.369116309164;64709.35290647033;96879;102863;102898.48550757198;1023.0792286941812;1045.2839804733783;1075.8924107589178;1080.9621663241749;1081.3079629037422;18935.526611834262;19252.614947701248;19281.807181928;19405.902970485058;19468.513287167934;31587;38647.806760966014;44830;45689.54310456986;46871.29693054675;53831.19253211284;54461.45538544564;55965;64904.67547662232;66524.33475665182;7684.385249180164;8258.545779982158;8887.066857979818;8905.55472226399;9266.258699303904;9264.073592640681;10033.397996120257;13311.33443327849;13436.596902092953;13893.30533473302;12009.519619215134;12115.697107572381;12233.204841685261;12243.20419172751;12313.507459701512;11863.881361186277;11927.165098443025;12019.278843269081;12254.080943929419;12556.748865022832;11820.23168494045;12043;12218;12539.811902821648;12625.116241862981;8324.458910170819;8436.367272454469;8457.323414126979;8474.957625211835;8508.27679647237;11937.582184623496;11967.102467314811;12033.759324813629;12216.816747748613;12285.447204598499 16:09:43 INFO - PROCESS | 23245 | |3;mootools.html;1400.012990841457;1509.6376869551311;1548.8915775895687;1685.9409920652772;1801.504586238785;8853.468791872492;9212.723618291446;9300.162985331319;9538.761530961729;9543.188828949531;9431.481268530228;9485.90514094859;9492.288078394113;9505.19205867502;9532.523373831298;2457.225973818246;2481.913133040344;2482.6772519572432;2484.9006039758387;2497.3881399057254;2447.6328550717444;2513.0576033987254;2514.35883849619;2514.7233804281514;2516.6350879122515;2413.6741539892155;2427.1141033522813;2477.752224777517;2484.7266800651914;2485.614729716897;2345.683332750074;2371.905123795046;2387.916422925198;2402;2405.2543711449507;2352.588297048027;2354.835161538693;2356.740758516562;2362.7400985891536;2364.054378248697;9318.720438386792;9394.34239603228;9449.102335278138;9474.05259474054;9502.192313653279;5502.0371434998715;5623.240862483594;5625.156226566007;5626.746796394172;5628.549716022709;3324.7007769300635;3335.949960750591;3356.932861342784;3368.2589830237307;3396.915077123067;1111.1388673777806;1131.095123900876;1135.2734250079955;1137.1073707139856;1159.162727077583;13205.669858253525;13230.206187628773;13251.536196233183;13315.800262996063;13335.86664133356;4658.883527911761;4661.440627124699;4778.354922085474;4787.3776409066595;4944.035912996931;10449.477526123663;10459.267851250339;10459.477026148663;10459.581616735246;10475.947620262003;5574.136008918624;5616.719164041781;5619.7471113799975;5628.746706398222;5632.070708333161;4458.665600080007;4685.437747470325;5093.719845408538;5193.818216362409;5230.92153617696;3989.7007724420782;4072.0430698785763;4075.5313138988804;4086.346184610448;4185.539590645027;1193.886580774825;1209.7520008398258;1231.3720002798636;1232.6117273058958;1245.2030700351781;955.4267439536222;1036.43514284715;1051.374432212832;1053.4995876958537;1053.794510070529;6193.102000210034;6340.524460665376;6365.363463653597;6381.872362552723;6561.475081993429;478.6386278359886;486.72013592184635;486.9172240719086;1074.6883403812963;1104.1498046504146;4531.13908357411;4563.748993805306;4614.2617181250835;4687.086018226481;4702.811887524461;10700.892991070144;10962.671119866416;10963.287386319864;10984.780304394028;11283;11720.413979301002;11748.41534862329;11759.706007349885;11762.764744705059;11770.646880593597;8681.348898832613;8883.067277935854;8906.55467226636;8910.955445222862;8924.241439477588;6620.536562440776;6747.662616869039;6754.3921047106;6818.488613354118;6821.693023813841;10907.29102608328;11020.283681560673;11088.5564577416;11106.278091924;11128.109751220045;11039.448027598748;11070.28043177191;11340.886591134313;11406.372649504357;11426.885731142582;8564.314854811475;8581.35639827003;8592.183742544448;8679.045305016569;8692.52191129494;9056.456612603264;9092.318076144447;9126.817463650823;9136.725898223063;9136.862947055666;8345.165483451607;8413.116622754522;8424.410291279552;8588.914110859061;8692.087330830425;8212.917870821215;8295.253427191341;8322.20939010793;8330.291925186428;8491.23578877904;7496.812579685551;7561;7584.696612135453;7654;7766.728164514215;5669.291338582677;5831.883362332815;5885.14665373527;5925.466707996301;5959.063219708622;5776.855578610568;5873.823785286449;5892.941070589411;5908.645481271137;5939.970300148471 16:09:43 INFO - PROCESS | 23245 | |4;prototype.html;356.7520573201625;357.64771699875615;358.4014695458584;360.71863946122033;361.4235294704945;5486.3690675572325;5514.889702205964;5516.696581688006;5518.641288316257;5522.22688823564;5383.623146379755;5426.9185962210595;5461.1262198048225;5486.807961721341;5488.56091512679;3968.3849003404516;3978.2441336146185;4300.268954277781;4312.964888426771;4375.4311939447825;4310.956890431087;4311.223979683656;4338.696291259613;4353.804078816461;4369.388285640013;4315.719478233905;4319.287317592593;4319.308910574308;4323.589259020404;4334.696571240014;4103.835846566119;4134.462519872427;4250.3836943643;4257.106007738379;4316.316636511763;3788.9431658525145;3793.3688513938996;3803.220339830328;3803.6006219347123;3804.5434547854156;5265.552428043621;5268.209768534713;5294.894102117974;5315.946840531584;5344.305240318772;4769.761511924425;4869.4156701195725;4877.634177436671;4881.951180488185;4903.828366007208;5615.961047206239;5624.3250809902665;5652.106544307662;5655.802046928337;5676.1769543416385;4176.4570605821355;4182.53992060873;4195.286801243796;4200.579942005806;4201.600847919473;5715.6851475152625;5815.73829177688;5828.5628577856405;5833.250024992528;5904.822855314347;4237.936430953539;4238.8766976751185;4245.957540424618;4259.7231179973205;4282.229198744256;4342.9782851086175;4343.9348409773875;4376.627986621109;4377.321515165155;4385.780710964439;4683.461401938821;4865.416150062015;4870.683405578626;4882.998985208023;4946.466595355451;5685.346185188673;5717.971410142923;5719.628224165416;5727.169560413717;5729.828105156852;1463.1879306984513;1463.239115659851;1464.7436698577706;1469.3608280398062;1481.4370539195252;947.5025611553989;954.494118117385;977.4721650308895;1030.0935177044153;1040.1730624153815;5471.480209380103;5512.834614961557;5573.860653483695;5585.413531579207;5598.188262701886;5836;5841.591088623755;5845.649261044351;5855.121731740188;5873.794417195463;5370.516653501204;5503.752331145108;5582.581306402036;5587.8603034923635;5604.187392828017;3056.838401407451;3077.476828939041;3080.5687203791485;3083.383323335297;3091.8608662610236;5874.941250587525;6042.486388656926;6050.485708714808;6069;6086.2087928569;5916.2013128228;5918.674472904032;5921.496672782861;5926.762929482772;5963.254593175853;6022.247219097613;6023.819285421444;6043.9725246707285;6045.153678485016;6077.11881777131;5674.9501342251815;5728;5792.855178620568;5817.563682723898;5843.983825580168;5710.600257982068;5722.341930677859;5722.685252311163;5729.885402292014;5763.193152958673;5788.942110579009;5999.460048595647;6017.930855263934;6024.385368778698;6071.574989750675;4207.516135644318;4245.426867372988;4252.425922500546;4272.5727427257025;4278.272693642026;5917.319508256433;5943.970280148572;5944.464998150187;5989.460948514655;6003.5797494174985;5681.829545113653;5700.315962084576;5744.420284421817;5875.030619947661;5883.176355310346;5715.799682066812;5720.170575266647;5726.685032323096;5726.742296596746;5750.7987220447085;5461.754221059982;5717.313922329347;5719.685417302088;5736.4263573643975;5748.48263656273;5356.544693700923;5716.971415142898;5727.513161381163;5737.713114344183;5748.6263392881;5595;5596.384397716167;5607.691576963306;5612.579056570692;5629.831105066854 16:09:43 INFO - PROCESS | 23245 | |5;yui.html;793.9047314322279;800.7117437722419;808.1191501263623;831.817000259943;908.1191244492848;15182.620434489143;15360.385584576603;15556.611084722888;15943.840561594381;16005.119718415497;15097.924510377461;15101.848981510182;15121.84878151218;15137.621559461018;15166.620834479143;730.9524880882752;859.8538248497771;872.1932212703256;885.1767855894016;885.5616469847434;879.6657270237333;885.3138817416522;887.5118684723409;888.2716172738357;888.4802390601506;931.222429271559;931.2410385535801;933.5612262236738;933.6078846884325;934.6495064350869;759.1004659478508;891.6388862510694;921.6451666108529;923.6490133749165;924.2282693950526;683.4498228925713;684.1619016704547;684.3806355248508;684.8630273945231;684.9794506164823;15851;15945.043297402193;15950.042997420076;15965.201739912958;15969.520914372588;14958.177300248482;15211.467598634106;15397.692046159014;15751.054936703835;15769.763453548207;13492.392842322119;13567.796483052762;13783.931080344635;14203.78694319586;14231.928840355835;4504.459464864204;4553.726776393394;4686.133065382915;4755.904881902377;4764.904701905977;84610.57694711549;86840.13159868383;87963.56018219868;88537.55731221303;88892;11496.597619083292;12227.694307642203;12292.385380730928;12428.813567796491;12503.687407814878;22023.66964495534;22055.779442205694;22091.66862497064;22098;22102.226422075473;9153.130452606993;9205.309601779893;9288.90711092894;9341.953290233505;9351.672691455902;5237.476252374809;5300.840974770763;5331.573474122061;5333.466653334635;5446.373667028263;3488.1454043758936;3570.5001299818045;3595.8741444049333;3602.729795265313;3609.4946707460977;641.5028353026441;648.1832890557931;649.4122818848855;655.5214693273841;659.0246435275723;655.2464665634463;681.7000519771285;695.6904177641003;722.2380388689983;730.5397599512379;9668.226541876633;9768.609255629695;9824.754381140528;9948.552315145836;9992.80014399708;594.0524862843757;597.2146627185238;597.4533302028682;684.736376495053;699.136566340569;4690.226112691368;4692.577668009895;4748.6675932685075;4752.1683705351425;4830.686005409637;127824;130880.3455982714;131922;132666;135707;104736;108713.45643271733;109110;110277.44861275642;110637.44681276543;91624;93016;93174;97340.51329743447;98261.50869245607;8602.268807151462;8613.26787223068;8888.688895888612;9074.319426043205;9075.183233509017;56994;57240.427595725174;57473;58600.4139958612;59564.70217648884;80687.59656201681;82524;84145;84565;87911;12213.267203967791;12228.388580570758;12295.877041229473;12480.438380273092;12840.743185136433;13665.31673416345;13773.586792396243;13889.097208681404;14021.719565608835;16005.839941600902;14370.712585748017;14635.121892686473;15021.474248401253;15098.773518397453;15446.382144714085;14095.083819551697;14784.63038424048;15276.312565934782;16233.918830405772;16838.410655626994;14990.400383984519;15228.162451065298;15237.771433428728;16518.174091295627;16520.504384868473;11178.00942567277;11387.715307117389;11527.711807204887;11754.23597466162;11985.340806255392;12640.30478323701;12826.807597885852;12913.022049415513;13102.410391532594;13124.934375328063 16:09:43 INFO - PROCESS | 23245 | __end_tp_report 16:09:43 INFO - PROCESS | 23245 | __start_cc_report 16:09:43 INFO - PROCESS | 23245 | _x_x_mozilla_cycle_collect,992 16:09:43 INFO - PROCESS | 23245 | __end_cc_report 16:09:43 INFO - PROCESS | 23245 | __startTimestamp1473721783103__endTimestamp 16:09:43 INFO - PROCESS | 23245 | 16:09:43 INFO - PROCESS | 23245 | ------- Summary: start ------- 16:09:43 INFO - PROCESS | 23245 | Number of tests: 6 16:09:43 INFO - PROCESS | 23245 | 16:09:43 INFO - PROCESS | 23245 | [#0] dojo.html Cycles:165 Average:5448.53 Median:5607.24 stddev:615.64 (11.0%) stddev-sans-first:589.37 16:09:43 INFO - PROCESS | 23245 | Values: 3102.0 3106.4 3129.0 3153.7 3160.2 5414.4 5525.9 5526.7 5550.8 5552.2 5518.0 5543.6 5543.7 5548.1 5557.1 4897.6 5017.9 5040.6 5087.6 5101.9 4859.6 4978.9 4983.4 4998.1 5000.8 5106.6 5121.1 5121.3 5124.0 5145.2 5104.3 5106.2 5107.8 5114.4 5135.9 4856.5 4913.3 4957.8 5017.8 5022.1 5457.6 5463.1 5466.8 5473.5 5536.4 5802.4 5855.9 5862.9 5871.6 5889.1 5671.9 5799.3 5850.8 5858.4 5912.9 5526.1 5541.6 5544.0 5562.0 5568.1 6007.4 6042.7 6044.8 6049.0 6054.3 5520.4 5666.9 5794.7 5800.2 5804.0 5798.8 5868.6 5875.9 5908.8 5910.6 5803.3 5848.6 5856.9 5859.2 5866.0 5647.9 5684.5 5782.4 5862.3 5878.3 4445.3 4451.8 4455.5 4467.8 4537.4 4012.7 4014.2 4015.9 4031.5 4046.2 5550.3 5553.4 5558.5 5560.2 5585.1 5673.4 5777.0 5797.4 5819.7 5918.1 5598.5 5604.4 5606.6 5607.2 5629.1 4943.1 4986.0 4990.8 4991.4 5028.7 5866.7 5993.9 6009.4 6039.9 6140.0 5891.7 5923.6 5923.9 5926.8 5948.0 6085.1 6096.5 6114.2 6131.2 6141.5 5552.3 5755.9 5771.3 5787.7 5804.2 5839.6 5896.6 5897.0 5898.9 5899.0 5770.1 5772.5 5772.9 5775.5 5780.3 5528.0 5603.7 5635.3 5656.5 5781.2 5693.4 5708.7 5709.6 5711.2 5719.5 5722.9 5729.2 5738.8 5744.4 5744.7 5382.5 5480.8 5531.6 5551.9 5567.2 16:09:43 INFO - PROCESS | 23245 | 16:09:43 INFO - PROCESS | 23245 | [#1] ext.html Cycles:180 Average:62349.97 Median:387421.50 stddev:120481.81 (31.1%) stddev-sans-first:120730.55 16:09:43 INFO - PROCESS | 23245 | Values: 589.7 621.4 621.8 647.0 653.6 36345.0 46949.8 47990.3 48023.3 48055.3 49534.3 49914.0 49947.3 50008.5 50015.0 8578.4 9369.6 9506.9 9534.0 11284.4 10981.2 11437.6 11439.7 11442.3 11442.7 11412.1 11434.1 11488.8 11493.9 11496.0 8677.7 11131.2 11401.1 11432.9 11483.9 11247.0 11337.0 11343.5 11346.3 11353.2 46529.8 46823.8 46912.3 46935.8 47006.5 3773.2 3782.7 3838.2 3841.2 3996.6 36311.3 36606.6 36802.0 36880.6 37190.4 4887.9 4926.4 4963.5 5029.9 5167.0 384157.0 385712.0 386999.0 387367.0 387476.0 38573.0 39137.2 39700.2 41126.6 65778.7 41307.8 41443.4 41484.8 41567.2 41655.2 17405.3 17508.0 17555.9 17571.2 17571.4 13315.7 13655.7 14324.7 14845.1 14902.0 9302.1 9315.8 9320.8 9377.7 9391.2 3425.2 3499.2 3506.2 3537.6 3572.4 1141.3 1183.0 1186.6 1197.9 1210.8 32953.7 34537.1 34559.5 35227.3 35227.6 1162.6 1194.5 1207.6 1212.4 1255.5 18334.6 18361.5 18475.0 18531.5 18654.3 489951.0 492534.0 494556.0 494826.0 496861.0 62312.4 62909.7 63173.0 63211.4 63311.7 1274.3 1407.2 1418.5 1423.5 1441.3 35203.1 37572.4 38124.2 38167.8 38246.4 56489.2 57157.1 57164.4 57164.7 57205.4 478668.0 478830.0 479940.0 480540.0 480989.0 31114.4 32090.7 32427.2 33586.0 33593.7 54169.2 54343.2 54523.7 54666.2 54689.5 43475.6 44738.3 45786.3 46723.0 47352.3 44287.8 45708.0 46327.3 46408.0 47525.8 45753.0 46532.8 46962.5 47125.5 47310.0 37488.0 39946.8 40429.8 40867.8 41302.6 29981.0 30923.4 31212.4 31407.4 31723.4 16:09:43 INFO - PROCESS | 23245 | 16:09:43 INFO - PROCESS | 23245 | [#2] jquery.html Cycles:180 Average:33760.72 Median:23692.91 stddev:104357.73 (440.5%) stddev-sans-first:104620.41 16:09:43 INFO - PROCESS | 23245 | Values: 397.9 402.4 428.7 431.2 442.9 18357.6 20159.8 20518.1 20636.7 20749.9 19812.7 19872.4 20457.7 20534.1 20571.8 5595.2 5964.2 6005.1 6016.8 6016.9 4447.1 5891.3 6352.9 6626.4 6666.6 6508.8 6727.6 6736.3 6742.9 6743.0 6697.8 6733.5 6803.2 6845.0 6849.9 5833.7 6026.6 6056.6 6068.6 6068.7 25377.4 25454.7 25617.9 25858.5 25863.6 2540.8 2652.3 2658.3 2674.7 3194.4 10839.3 11093.1 11241.3 11443.0 11494.9 2005.1 2072.6 2085.9 2094.3 2099.6 88323.6 90427.0 90675.0 91987.1 94538.5 11419.7 11592.6 11786.4 11936.8 12016.3 16698.4 16707.9 16742.2 16761.7 16763.9 8980.9 9031.0 9047.3 9106.7 9121.8 8897.8 8979.5 8982.2 9166.5 9189.6 4953.2 5000.3 5025.8 5101.6 5131.8 2024.6 2052.3 2066.6 2067.2 2069.9 1007.4 1015.3 1025.3 1036.3 1112.6 19982.8 21615.8 21744.2 21854.6 22008.4 1013.8 1038.5 1040.3 1042.7 1067.6 11001.3 12054.9 12060.8 12126.0 12166.3 615875.0 635803.0 639423.0 643666.0 647848.0 63088.4 64709.4 96879.0 102863.0 102898.5 1023.1 1045.3 1075.9 1081.0 1081.3 18935.5 19252.6 19281.8 19405.9 19468.5 31587.0 38647.8 44830.0 45689.5 46871.3 53831.2 54461.5 55965.0 64904.7 66524.3 7684.4 8258.5 8887.1 8905.6 9266.3 9264.1 10033.4 13311.3 13436.6 13893.3 12009.5 12115.7 12233.2 12243.2 12313.5 11863.9 11927.2 12019.3 12254.1 12556.7 11820.2 12043.0 12218.0 12539.8 12625.1 8324.5 8436.4 8457.3 8475.0 8508.3 11937.6 11967.1 12033.8 12216.8 12285.4 16:09:43 INFO - PROCESS | 23245 | 16:09:43 INFO - PROCESS | 23245 | [#3] mootools.html Cycles:180 Average:6214.21 Median:4660.16 stddev:3563.16 (76.5%) stddev-sans-first:3554.79 16:09:43 INFO - PROCESS | 23245 | Values: 1400.0 1509.6 1548.9 1685.9 1801.5 8853.5 9212.7 9300.2 9538.8 9543.2 9431.5 9485.9 9492.3 9505.2 9532.5 2457.2 2481.9 2482.7 2484.9 2497.4 2447.6 2513.1 2514.4 2514.7 2516.6 2413.7 2427.1 2477.8 2484.7 2485.6 2345.7 2371.9 2387.9 2402.0 2405.3 2352.6 2354.8 2356.7 2362.7 2364.1 9318.7 9394.3 9449.1 9474.1 9502.2 5502.0 5623.2 5625.2 5626.7 5628.5 3324.7 3335.9 3356.9 3368.3 3396.9 1111.1 1131.1 1135.3 1137.1 1159.2 13205.7 13230.2 13251.5 13315.8 13335.9 4658.9 4661.4 4778.4 4787.4 4944.0 10449.5 10459.3 10459.5 10459.6 10475.9 5574.1 5616.7 5619.7 5628.7 5632.1 4458.7 4685.4 5093.7 5193.8 5230.9 3989.7 4072.0 4075.5 4086.3 4185.5 1193.9 1209.8 1231.4 1232.6 1245.2 955.4 1036.4 1051.4 1053.5 1053.8 6193.1 6340.5 6365.4 6381.9 6561.5 478.6 486.7 486.9 1074.7 1104.1 4531.1 4563.7 4614.3 4687.1 4702.8 10700.9 10962.7 10963.3 10984.8 11283.0 11720.4 11748.4 11759.7 11762.8 11770.6 8681.3 8883.1 8906.6 8911.0 8924.2 6620.5 6747.7 6754.4 6818.5 6821.7 10907.3 11020.3 11088.6 11106.3 11128.1 11039.4 11070.3 11340.9 11406.4 11426.9 8564.3 8581.4 8592.2 8679.0 8692.5 9056.5 9092.3 9126.8 9136.7 9136.9 8345.2 8413.1 8424.4 8588.9 8692.1 8212.9 8295.3 8322.2 8330.3 8491.2 7496.8 7561.0 7584.7 7654.0 7766.7 5669.3 5831.9 5885.1 5925.5 5959.1 5776.9 5873.8 5892.9 5908.6 5940.0 16:09:43 INFO - PROCESS | 23245 | 16:09:43 INFO - PROCESS | 23245 | [#4] prototype.html Cycles:180 Average:4827.15 Median:5517.67 stddev:1403.75 (25.4%) stddev-sans-first:1366.99 16:09:43 INFO - PROCESS | 23245 | Values: 356.8 357.6 358.4 360.7 361.4 5486.4 5514.9 5516.7 5518.6 5522.2 5383.6 5426.9 5461.1 5486.8 5488.6 3968.4 3978.2 4300.3 4313.0 4375.4 4311.0 4311.2 4338.7 4353.8 4369.4 4315.7 4319.3 4319.3 4323.6 4334.7 4103.8 4134.5 4250.4 4257.1 4316.3 3788.9 3793.4 3803.2 3803.6 3804.5 5265.6 5268.2 5294.9 5315.9 5344.3 4769.8 4869.4 4877.6 4882.0 4903.8 5616.0 5624.3 5652.1 5655.8 5676.2 4176.5 4182.5 4195.3 4200.6 4201.6 5715.7 5815.7 5828.6 5833.3 5904.8 4237.9 4238.9 4246.0 4259.7 4282.2 4343.0 4343.9 4376.6 4377.3 4385.8 4683.5 4865.4 4870.7 4883.0 4946.5 5685.3 5718.0 5719.6 5727.2 5729.8 1463.2 1463.2 1464.7 1469.4 1481.4 947.5 954.5 977.5 1030.1 1040.2 5471.5 5512.8 5573.9 5585.4 5598.2 5836.0 5841.6 5845.6 5855.1 5873.8 5370.5 5503.8 5582.6 5587.9 5604.2 3056.8 3077.5 3080.6 3083.4 3091.9 5874.9 6042.5 6050.5 6069.0 6086.2 5916.2 5918.7 5921.5 5926.8 5963.3 6022.2 6023.8 6044.0 6045.2 6077.1 5675.0 5728.0 5792.9 5817.6 5844.0 5710.6 5722.3 5722.7 5729.9 5763.2 5788.9 5999.5 6017.9 6024.4 6071.6 4207.5 4245.4 4252.4 4272.6 4278.3 5917.3 5944.0 5944.5 5989.5 6003.6 5681.8 5700.3 5744.4 5875.0 5883.2 5715.8 5720.2 5726.7 5726.7 5750.8 5461.8 5717.3 5719.7 5736.4 5748.5 5356.5 5717.0 5727.5 5737.7 5748.6 5595.0 5596.4 5607.7 5612.6 5629.8 16:09:43 INFO - PROCESS | 23245 | 16:09:43 INFO - PROCESS | 23245 | [#5] yui.html Cycles:180 Average:22923.06 Median:4750.42 stddev:33766.55 (710.8%) stddev-sans-first:33820.39 16:09:43 INFO - PROCESS | 23245 | Values: 793.9 800.7 808.1 831.8 908.1 15182.6 15360.4 15556.6 15943.8 16005.1 15097.9 15101.8 15121.8 15137.6 15166.6 731.0 859.9 872.2 885.2 885.6 879.7 885.3 887.5 888.3 888.5 931.2 931.2 933.6 933.6 934.6 759.1 891.6 921.6 923.6 924.2 683.4 684.2 684.4 684.9 685.0 15851.0 15945.0 15950.0 15965.2 15969.5 14958.2 15211.5 15397.7 15751.1 15769.8 13492.4 13567.8 13783.9 14203.8 14231.9 4504.5 4553.7 4686.1 4755.9 4764.9 84610.6 86840.1 87963.6 88537.6 88892.0 11496.6 12227.7 12292.4 12428.8 12503.7 22023.7 22055.8 22091.7 22098.0 22102.2 9153.1 9205.3 9288.9 9342.0 9351.7 5237.5 5300.8 5331.6 5333.5 5446.4 3488.1 3570.5 3595.9 3602.7 3609.5 641.5 648.2 649.4 655.5 659.0 655.2 681.7 695.7 722.2 730.5 9668.2 9768.6 9824.8 9948.6 9992.8 594.1 597.2 597.5 684.7 699.1 4690.2 4692.6 4748.7 4752.2 4830.7 127824.0 130880.3 131922.0 132666.0 135707.0 104736.0 108713.5 109110.0 110277.4 110637.4 91624.0 93016.0 93174.0 97340.5 98261.5 8602.3 8613.3 8888.7 9074.3 9075.2 56994.0 57240.4 57473.0 58600.4 59564.7 80687.6 82524.0 84145.0 84565.0 87911.0 12213.3 12228.4 12295.9 12480.4 12840.7 13665.3 13773.6 13889.1 14021.7 16005.8 14370.7 14635.1 15021.5 15098.8 15446.4 14095.1 14784.6 15276.3 16233.9 16838.4 14990.4 15228.2 15237.8 16518.2 16520.5 11178.0 11387.7 11527.7 11754.2 11985.3 12640.3 12826.8 12913.0 13102.4 13124.9 16:09:43 INFO - PROCESS | 23245 | -------- Summary: end -------- 16:09:43 INFO - PROCESS | 23245 | 16:09:43 INFO - TEST-INFO | 23245: exit 0 16:09:43 INFO - TEST-OK | dromaeo_css | took 1113754ms 16:09:43 INFO - TEST-START | kraken 16:09:43 INFO - operating with platform_type : linux_ 16:09:43 INFO - Initialising browser for kraken test... 16:09:43 INFO - TEST-INFO | started process 23446 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVJqFBn/profile http://localhost:33977/getInfo.html) 16:09:48 INFO - PROCESS | 23446 | __metrics Screen width/height:1600/1200 16:09:48 INFO - PROCESS | 23446 | colorDepth:24 16:09:48 INFO - PROCESS | 23446 | Browser inner width/height: 1024/697 16:09:48 INFO - PROCESS | 23446 | __metrics 16:09:49 INFO - PROCESS | 23446 | 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" 16:09:49 INFO - PROCESS | 23446 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:09:50 INFO - TEST-INFO | 23446: exit 0 16:09:50 INFO - Browser initialized. 16:09:50 INFO - Running cycle 1/1 for kraken test... 16:09:50 INFO - TEST-INFO | started process 23537 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVJqFBn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:10:02 INFO - PROCESS | 23537 | RSS: Main: 145764352 16:10:02 INFO - PROCESS | 23537 | 16:10:04 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33977/tests/kraken/driver.html?testName=audio-beat-detection) 16:10:05 INFO - PROCESS | 23537 | RSS: Main: 201121792 16:10:05 INFO - PROCESS | 23537 | 16:10:08 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33977/tests/kraken/driver.html?testName=audio-dft) 16:10:08 INFO - PROCESS | 23537 | RSS: Main: 185335808 16:10:08 INFO - PROCESS | 23537 | 16:10:11 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33977/tests/kraken/driver.html?testName=audio-fft) 16:10:12 INFO - PROCESS | 23537 | RSS: Main: 275877888 16:10:12 INFO - PROCESS | 23537 | 16:10:14 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33977/tests/kraken/driver.html?testName=audio-oscillator) 16:10:14 INFO - PROCESS | 23537 | RSS: Main: 181563392 16:10:14 INFO - PROCESS | 23537 | 16:10:17 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33977/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:10:17 INFO - PROCESS | 23537 | RSS: Main: 180617216 16:10:17 INFO - PROCESS | 23537 | 16:10:20 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33977/tests/kraken/driver.html?testName=imaging-darkroom) 16:10:21 INFO - PROCESS | 23537 | RSS: Main: 216195072 16:10:21 INFO - PROCESS | 23537 | 16:10:24 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33977/tests/kraken/driver.html?testName=imaging-desaturate) 16:10:25 INFO - PROCESS | 23537 | RSS: Main: 209891328 16:10:25 INFO - PROCESS | 23537 | 16:10:28 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33977/tests/kraken/driver.html?testName=json-parse-financial) 16:10:28 INFO - PROCESS | 23537 | RSS: Main: 212148224 16:10:28 INFO - PROCESS | 23537 | 16:10:30 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33977/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:10:30 INFO - PROCESS | 23537 | RSS: Main: 175591424 16:10:30 INFO - PROCESS | 23537 | 16:10:32 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:10:33 INFO - PROCESS | 23537 | RSS: Main: 183189504 16:10:33 INFO - PROCESS | 23537 | 16:10:34 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:10:35 INFO - PROCESS | 23537 | RSS: Main: 186032128 16:10:35 INFO - PROCESS | 23537 | 16:10:38 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:10:38 INFO - PROCESS | 23537 | RSS: Main: 180072448 16:10:38 INFO - PROCESS | 23537 | 16:10:41 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:10:41 INFO - PROCESS | 23537 | RSS: Main: 183504896 16:10:41 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | Cycle 1(1): loaded http://localhost:33977/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33977/tests/kraken/driver.html?testName=ai-astar) 16:10:43 INFO - PROCESS | 23537 | RSS: Main: 234496000 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | __start_tp_report 16:10:43 INFO - PROCESS | 23537 | _x_x_mozilla_page_load 16:10:43 INFO - PROCESS | 23537 | _x_x_mozilla_page_load_details 16:10:43 INFO - PROCESS | 23537 | |i|pagename|runs| 16:10:43 INFO - PROCESS | 23537 | |0;ai-astar;110;111;119;111;111;111;109;109;110;111 16:10:43 INFO - PROCESS | 23537 | |1;audio-beat-detection;150;117;151;167;115;147;152;117;167;115 16:10:43 INFO - PROCESS | 23537 | |2;audio-dft;161;161;160;162;160;161;161;160;161;160 16:10:43 INFO - PROCESS | 23537 | |3;audio-fft;74;75;74;166;75;75;73;76;161;75 16:10:43 INFO - PROCESS | 23537 | |4;audio-oscillator;84;83;153;82;83;83;142;83;83;149 16:10:43 INFO - PROCESS | 23537 | |5;imaging-gaussian-blur;116;116;116;117;116;116;116;116;116;116 16:10:43 INFO - PROCESS | 23537 | |6;imaging-darkroom;161;157;156;152;158;155;157;160;156;155 16:10:43 INFO - PROCESS | 23537 | |7;imaging-desaturate;95;95;94;94;95;95;95;95;94;95 16:10:43 INFO - PROCESS | 23537 | |8;json-parse-financial;49;49;49;49;50;50;51;49;49;49 16:10:43 INFO - PROCESS | 23537 | |9;json-stringify-tinderbox;58;60;58;58;58;58;58;58;59;59 16:10:43 INFO - PROCESS | 23537 | |10;stanford-crypto-aes;73;71;72;72;76;72;72;72;72;75 16:10:43 INFO - PROCESS | 23537 | |11;stanford-crypto-ccm;127;117;117;124;116;115;123;115;169;118 16:10:43 INFO - PROCESS | 23537 | |12;stanford-crypto-pbkdf2;152;153;148;148;149;153;150;153;151;150 16:10:43 INFO - PROCESS | 23537 | |13;stanford-crypto-sha256-iterative;63;61;64;61;61;60;61;60;60;60 16:10:43 INFO - PROCESS | 23537 | __end_tp_report 16:10:43 INFO - PROCESS | 23537 | __start_cc_report 16:10:43 INFO - PROCESS | 23537 | _x_x_mozilla_cycle_collect,1638 16:10:43 INFO - PROCESS | 23537 | __end_cc_report 16:10:43 INFO - PROCESS | 23537 | __startTimestamp1473721843764__endTimestamp 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | ------- Summary: start ------- 16:10:43 INFO - PROCESS | 23537 | Number of tests: 14 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:2.86 (2.6%) stddev-sans-first:3.00 16:10:43 INFO - PROCESS | 23537 | Values: 110.0 111.0 119.0 111.0 111.0 111.0 109.0 109.0 110.0 111.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:150.50 stddev:21.55 (14.3%) stddev-sans-first:22.54 16:10:43 INFO - PROCESS | 23537 | Values: 150.0 117.0 151.0 167.0 115.0 147.0 152.0 117.0 167.0 115.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 16:10:43 INFO - PROCESS | 23537 | Values: 161.0 161.0 160.0 162.0 160.0 161.0 161.0 160.0 161.0 160.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#3] audio-fft Cycles:10 Average:92.40 Median:75.00 stddev:37.50 (50.0%) stddev-sans-first:39.18 16:10:43 INFO - PROCESS | 23537 | Values: 74.0 75.0 74.0 166.0 75.0 75.0 73.0 76.0 161.0 75.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#4] audio-oscillator Cycles:10 Average:102.50 Median:83.00 stddev:31.51 (38.0%) stddev-sans-first:32.70 16:10:43 INFO - PROCESS | 23537 | Values: 84.0 83.0 153.0 82.0 83.0 83.0 142.0 83.0 83.0 149.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#5] imaging-gaussian-blur Cycles:10 Average:116.10 Median:116.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:10:43 INFO - PROCESS | 23537 | Values: 116.0 116.0 116.0 117.0 116.0 116.0 116.0 116.0 116.0 116.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#6] imaging-darkroom Cycles:10 Average:156.70 Median:157.00 stddev:2.58 (1.6%) stddev-sans-first:2.22 16:10:43 INFO - PROCESS | 23537 | Values: 161.0 157.0 156.0 152.0 158.0 155.0 157.0 160.0 156.0 155.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 16:10:43 INFO - PROCESS | 23537 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 95.0 95.0 94.0 95.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#8] json-parse-financial Cycles:10 Average:49.40 Median:49.00 stddev:0.70 (1.4%) stddev-sans-first:0.73 16:10:43 INFO - PROCESS | 23537 | Values: 49.0 49.0 49.0 49.0 50.0 50.0 51.0 49.0 49.0 49.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#9] json-stringify-tinderbox Cycles:10 Average:58.40 Median:58.00 stddev:0.70 (1.2%) stddev-sans-first:0.73 16:10:43 INFO - PROCESS | 23537 | Values: 58.0 60.0 58.0 58.0 58.0 58.0 58.0 58.0 59.0 59.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#10] stanford-crypto-aes Cycles:10 Average:72.70 Median:72.00 stddev:1.57 (2.2%) stddev-sans-first:1.66 16:10:43 INFO - PROCESS | 23537 | Values: 73.0 71.0 72.0 72.0 76.0 72.0 72.0 72.0 72.0 75.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#11] stanford-crypto-ccm Cycles:10 Average:124.10 Median:120.50 stddev:16.31 (13.5%) stddev-sans-first:17.27 16:10:43 INFO - PROCESS | 23537 | Values: 127.0 117.0 117.0 124.0 116.0 115.0 123.0 115.0 169.0 118.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.70 Median:151.50 stddev:2.00 (1.3%) stddev-sans-first:2.07 16:10:43 INFO - PROCESS | 23537 | Values: 152.0 153.0 148.0 148.0 149.0 153.0 150.0 153.0 151.0 150.0 16:10:43 INFO - PROCESS | 23537 | 16:10:43 INFO - PROCESS | 23537 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.10 Median:61.00 stddev:1.37 (2.2%) stddev-sans-first:1.27 16:10:43 INFO - PROCESS | 23537 | Values: 63.0 61.0 64.0 61.0 61.0 60.0 61.0 60.0 60.0 60.0 16:10:43 INFO - PROCESS | 23537 | -------- Summary: end -------- 16:10:43 INFO - PROCESS | 23537 | 16:10:44 INFO - TEST-INFO | 23537: exit 0 16:10:44 INFO - TEST-OK | kraken | took 60874ms 16:10:44 INFO - SUITE-END | took 1174s 16:10:44 INFO - Completed test suite (00:19:34) 16:10:44 INFO - javascript benchmark 16:10:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3101.9844900775493, 3106.394253120641, 3128.953065704015, 3153.7477001839857, 3160.2099475131217, 5414.377346605142, 5525.889482210358, 5526.668399896009, 5550.8057217997375, 5552.194931734906, 5517.972410137953, 5543.61194716369, 5543.695096769676, 5548.112302031675, 5557.138643510252, 4897.583705385046, 5017.899642007167, 5040.571551418134, 5087.618428617869, 5101.948980510203, 4859.562639362457, 4978.900421991558, 4983.352164218664, 4998.10034193845, 5000.849974500771, 5106.5659418949435, 5121.12940800063, 5121.308623335858, 5124.0, 5145.176771716526, 5104.336436263299, 5106.234064890259, 5107.770150343206, 5114.411842638107, 5135.948640513584, 4856.490068542787, 4913.336699545552, 4957.801687932479, 5017.8243761468175, 5022.121128802445, 5457.590680698963, 5463.07127788273, 5466.781328746885, 5473.507384335429, 5536.390996990328, 5802.448767367136, 5855.8536036599, 5862.882742345172, 5871.6183448075735, 5889.116632505158, 5671.8582035449035, 5799.3040835100055, 5850.76596936122, 5858.384869588717, 5912.852178695524, 5526.06056970316, 5541.5843811714285, 5544.002079625666, 5562.0, 5568.081266591008, 6007.429294217042, 6042.728077236535, 6044.848878778066, 6048.999999999912, 6054.334023257438, 5520.392756796749, 5666.914996275059, 5794.739236734357, 5800.187973683687, 5804.0, 5798.82603521895, 5868.618539794899, 5875.9118613220835, 5908.822735317947, 5910.556708246899, 5803.332616749043, 5848.619839710405, 5856.94143058564, 5859.1797148399255, 5865.970670146708, 5647.887042259132, 5684.459976302244, 5782.392848750905, 5862.296524417097, 5878.265216847894, 4445.26653102241, 4451.821927122944, 4455.509893911667, 4467.821287148543, 4537.38745269391, 4012.719109662354, 4014.217227640582, 4015.8996025099605, 4031.5162180538523, 4046.2312160689376, 5550.333959924754, 5553.416891226444, 5558.527525160326, 5560.221568980346, 5585.050541407971, 5673.375928647845, 5776.971115144397, 5797.36229014808, 5819.650820950672, 5918.141869428909, 5598.468145526169, 5604.355499117653, 5606.579506537026, 5607.243022192033, 5629.099344105005, 4943.060818444484, 4986.0, 4990.825321113816, 4991.351124353883, 5028.673136246132, 5866.7066646668345, 5993.940060599338, 6009.399060093956, 6039.879202416015, 6139.952147135068, 5891.734871930687, 5923.614965027259, 5923.881522369442, 5926.822195334147, 5948.018576934757, 6085.056816193497, 6096.512279017579, 6114.205153330038, 6131.202943617302, 6141.477974372228, 5552.278203833475, 5755.942440575541, 5771.307443106854, 5787.6816775076095, 5804.216430781959, 5839.591228613956, 5896.616719913192, 5896.970515147397, 5898.911516327173, 5899.026660601125, 5770.076787713946, 5772.509336706427, 5772.913406298825, 5775.48020678141, 5780.30636323627, 5528.0, 5603.663780173202, 5635.267415235993, 5656.462636049569, 5781.161731548986, 5693.373728889902, 5708.743106560131, 5709.628874123168, 5711.171880077449, 5719.456651618089, 5722.942770572241, 5729.226554415267, 5738.770449181986, 5744.425557444222, 5744.655320680772, 5382.461753824587, 5480.835574932758, 5531.557475402039, 5551.916721249104, 5567.164925261081], "value": 5406.7135683383185, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.7287247865981, 621.3506885304856, 621.8445388652837, 646.9644169570672, 653.5653790229496, 36345.0, 46949.76525117374, 47990.280145797755, 48023.27965080522, 48055.279170812464, 49534.25698614515, 49914.0, 49947.25079123816, 50008.49991500084, 50015.0, 8578.39951203416, 9369.625214991393, 9506.857397139049, 9534.0, 11284.379359135244, 10981.17641176915, 11437.599684011062, 11439.656810295704, 11442.313461192354, 11442.656720298362, 11412.087033037336, 11434.142439317044, 11488.827667584992, 11493.942530287337, 11496.022838058774, 8677.69628063021, 11131.165162612837, 11401.087912967027, 11432.942835285854, 11483.942580287045, 11246.98777110056, 11337.0, 11343.546258149747, 11346.262492938014, 11353.20527563071, 46529.76735116337, 46823.765881170715, 46912.29631555529, 46935.765321173516, 47006.52993470056, 3773.1887644156363, 3782.6973842092566, 3838.193979264358, 3841.2125514269787, 3996.5603783583783, 36311.27377452463, 36606.63393366059, 36802.0, 36880.63119368799, 37190.4421433676, 4887.926681099786, 4926.3842019747535, 4963.478834722375, 5029.949700503022, 5167.0, 384157.0, 385712.0, 386999.0, 387366.9999999972, 387476.0, 38573.0, 39137.2172556553, 39700.2059958805, 41126.58873411288, 65778.67110664416, 41307.7934610325, 41443.37834932478, 41484.792576036925, 41567.1686566267, 41655.1668966625, 17405.303787848345, 17508.0, 17555.912220438815, 17571.209295581728, 17571.385001524886, 13315.66710832237, 13655.658608534866, 14324.713505729827, 14845.109293442429, 14902.0, 9302.069793020779, 9315.813683726288, 9320.766980825534, 9377.718668439958, 9391.154796068387, 3425.246445781924, 3499.232887391851, 3506.1760486285702, 3537.557805274341, 3572.4105522589, 1141.315210873486, 1182.9704257393635, 1186.6262127429832, 1197.8921897029309, 1210.7881120803825, 32953.67046329554, 34537.136571585914, 34559.4816077759, 35227.295454090774, 35227.64772352295, 1162.581470670558, 1194.4565222823594, 1207.6497815633368, 1212.4483360071147, 1255.4978008796554, 18334.633307333777, 18361.540961475803, 18475.0, 18531.536711582048, 18654.34709785178, 489951.0, 492534.0, 494556.0, 494826.0, 496861.0, 62312.37687623066, 62909.685451573365, 63173.0, 63211.367886321466, 63311.6834415825, 1274.3055035005943, 1407.2119613016744, 1418.546065259107, 1423.4590855474853, 1441.2721575604044, 35203.11992200215, 37572.436413454365, 38124.2375152501, 38167.80916095513, 38246.42630360491, 56489.15266270927, 57157.142642861225, 57164.42835571591, 57164.71417642885, 57205.42794572001, 478668.0, 478830.0, 479940.0, 480540.0, 480989.0, 31114.377712446076, 32090.679093208768, 32427.18932026718, 33586.0, 33593.66406335905, 54169.187462187314, 54343.18485222804, 54523.72738136284, 54666.18000730072, 54689.453105468434, 43475.56524434715, 44738.28487126058, 45786.31320530261, 46723.0, 47352.28971565498, 44287.778561106985, 45708.0, 46327.305090423, 46408.0, 47525.76237118792, 45753.0, 46532.7673361631, 46962.530374697184, 47125.52874471348, 47310.0, 37488.0, 39946.800265998485, 40429.79785101055, 40867.795661021504, 41302.586974129874, 29981.0, 30923.44380396193, 31212.375752484368, 31407.371852562363, 31723.365532688757], "value": 20962.51019927463, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.884613462096, 402.39640539191214, 428.712762449159, 431.2474731593369, 442.89813342931126, 18357.632847343062, 20159.798402015975, 20518.076686549102, 20636.690449643247, 20749.896250518763, 19812.70280945787, 19872.40382788515, 20457.693134602992, 20534.075966581502, 20571.794282057173, 5595.160725891108, 5964.194833697438, 6005.12925625784, 6016.849578760544, 6016.939830601704, 4447.11057788442, 5891.293044834626, 6352.904706429407, 6626.370494802986, 6666.600003999752, 6508.8047358579315, 6727.629980351103, 6736.2590115087305, 6742.932570674279, 6743.02226177204, 6697.799066028027, 6733.528652994292, 6803.217629972542, 6844.965775171092, 6849.863002739918, 5833.679147646878, 6026.608270462406, 6056.6063205891915, 6068.635881847059, 6068.726907289182, 25377.365565861, 25454.745452545423, 25617.871910640326, 25858.482830343477, 25863.61204581933, 2540.8221424500293, 2652.310399296197, 2658.3088397016913, 2674.71915448877, 3194.361127774431, 10839.349639021606, 11093.112550995982, 11241.325520468798, 11443.0, 11494.885051149466, 2005.0977060322766, 2072.5544008038346, 2085.9165633374646, 2094.2984100326407, 2099.611571859211, 88323.55838220831, 90427.0, 90675.0, 91987.08012919853, 94538.52730736302, 11419.714507137222, 11592.594259200887, 11786.410679466164, 11936.761264774656, 12016.279023258632, 16698.4155554555, 16707.91646041762, 16742.24659890308, 16761.7485737714, 16763.916180419263, 8980.910190898137, 9030.954845225731, 9047.276217902552, 9106.726798195932, 9121.77195570116, 8897.777555561162, 8979.46123232608, 8982.191602755784, 9166.450012999241, 9189.63241470347, 4953.18272485036, 5000.274960130833, 5025.824096156617, 5101.591872650179, 5131.7690703918415, 2024.5545979884403, 2052.332991777678, 2066.6176757299645, 2067.173130747713, 2069.9482512937298, 1007.4257673126394, 1015.2690063154517, 1025.3130402630256, 1036.2797855490362, 1112.593903225317, 19982.800171998384, 21615.78384216169, 21744.238951636617, 21854.562908741736, 22008.44978875509, 1013.8225810483136, 1038.4911393417322, 1040.256216804988, 1042.697617690861, 1067.5783065689166, 11001.284916480403, 12054.879451205552, 12060.819087713691, 12126.0, 12166.330851803235, 615875.0, 635803.0, 639423.0, 643666.0, 647848.0, 63088.369116309164, 64709.35290647033, 96879.0, 102863.0, 102898.48550757198, 1023.0792286941812, 1045.2839804733783, 1075.8924107589178, 1080.9621663241749, 1081.3079629037422, 18935.526611834262, 19252.614947701248, 19281.807181928, 19405.902970485058, 19468.513287167934, 31587.0, 38647.806760966014, 44830.0, 45689.54310456986, 46871.29693054675, 53831.19253211284, 54461.45538544564, 55965.0, 64904.67547662232, 66524.33475665182, 7684.385249180164, 8258.545779982158, 8887.066857979818, 8905.55472226399, 9266.258699303904, 9264.073592640681, 10033.397996120257, 13311.33443327849, 13436.596902092953, 13893.30533473302, 12009.519619215134, 12115.697107572381, 12233.204841685261, 12243.20419172751, 12313.507459701512, 11863.881361186277, 11927.165098443025, 12019.278843269081, 12254.080943929419, 12556.748865022832, 11820.23168494045, 12043.0, 12218.0, 12539.811902821648, 12625.116241862981, 8324.458910170819, 8436.367272454469, 8457.323414126979, 8474.957625211835, 8508.27679647237, 11937.582184623496, 11967.102467314811, 12033.759324813629, 12216.816747748613, 12285.447204598499], "value": 9858.601554158746, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.012990841457, 1509.6376869551311, 1548.8915775895687, 1685.9409920652772, 1801.504586238785, 8853.468791872492, 9212.723618291446, 9300.162985331319, 9538.761530961729, 9543.188828949531, 9431.481268530228, 9485.90514094859, 9492.288078394113, 9505.19205867502, 9532.523373831298, 2457.225973818246, 2481.913133040344, 2482.6772519572432, 2484.9006039758387, 2497.3881399057254, 2447.6328550717444, 2513.0576033987254, 2514.35883849619, 2514.7233804281514, 2516.6350879122515, 2413.6741539892155, 2427.1141033522813, 2477.752224777517, 2484.7266800651914, 2485.614729716897, 2345.683332750074, 2371.905123795046, 2387.916422925198, 2402.0, 2405.2543711449507, 2352.588297048027, 2354.835161538693, 2356.740758516562, 2362.7400985891536, 2364.054378248697, 9318.720438386792, 9394.34239603228, 9449.102335278138, 9474.05259474054, 9502.192313653279, 5502.0371434998715, 5623.240862483594, 5625.156226566007, 5626.746796394172, 5628.549716022709, 3324.7007769300635, 3335.949960750591, 3356.932861342784, 3368.2589830237307, 3396.915077123067, 1111.1388673777806, 1131.095123900876, 1135.2734250079955, 1137.1073707139856, 1159.162727077583, 13205.669858253525, 13230.206187628773, 13251.536196233183, 13315.800262996063, 13335.86664133356, 4658.883527911761, 4661.440627124699, 4778.354922085474, 4787.3776409066595, 4944.035912996931, 10449.477526123663, 10459.267851250339, 10459.477026148663, 10459.581616735246, 10475.947620262003, 5574.136008918624, 5616.719164041781, 5619.7471113799975, 5628.746706398222, 5632.070708333161, 4458.665600080007, 4685.437747470325, 5093.719845408538, 5193.818216362409, 5230.92153617696, 3989.7007724420782, 4072.0430698785763, 4075.5313138988804, 4086.346184610448, 4185.539590645027, 1193.886580774825, 1209.7520008398258, 1231.3720002798636, 1232.6117273058958, 1245.2030700351781, 955.4267439536222, 1036.43514284715, 1051.374432212832, 1053.4995876958537, 1053.794510070529, 6193.102000210034, 6340.524460665376, 6365.363463653597, 6381.872362552723, 6561.475081993429, 478.6386278359886, 486.72013592184635, 486.9172240719086, 1074.6883403812963, 1104.1498046504146, 4531.13908357411, 4563.748993805306, 4614.2617181250835, 4687.086018226481, 4702.811887524461, 10700.892991070144, 10962.671119866416, 10963.287386319864, 10984.780304394028, 11283.0, 11720.413979301002, 11748.41534862329, 11759.706007349885, 11762.764744705059, 11770.646880593597, 8681.348898832613, 8883.067277935854, 8906.55467226636, 8910.955445222862, 8924.241439477588, 6620.536562440776, 6747.662616869039, 6754.3921047106, 6818.488613354118, 6821.693023813841, 10907.29102608328, 11020.283681560673, 11088.5564577416, 11106.278091924, 11128.109751220045, 11039.448027598748, 11070.28043177191, 11340.886591134313, 11406.372649504357, 11426.885731142582, 8564.314854811475, 8581.35639827003, 8592.183742544448, 8679.045305016569, 8692.52191129494, 9056.456612603264, 9092.318076144447, 9126.817463650823, 9136.725898223063, 9136.862947055666, 8345.165483451607, 8413.116622754522, 8424.410291279552, 8588.914110859061, 8692.087330830425, 8212.917870821215, 8295.253427191341, 8322.20939010793, 8330.291925186428, 8491.23578877904, 7496.812579685551, 7561.0, 7584.696612135453, 7654.0, 7766.728164514215, 5669.291338582677, 5831.883362332815, 5885.14665373527, 5925.466707996301, 5959.063219708622, 5776.855578610568, 5873.823785286449, 5892.941070589411, 5908.645481271137, 5939.970300148471], "value": 4895.690669820852, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.7520573201625, 357.64771699875615, 358.4014695458584, 360.71863946122033, 361.4235294704945, 5486.3690675572325, 5514.889702205964, 5516.696581688006, 5518.641288316257, 5522.22688823564, 5383.623146379755, 5426.9185962210595, 5461.1262198048225, 5486.807961721341, 5488.56091512679, 3968.3849003404516, 3978.2441336146185, 4300.268954277781, 4312.964888426771, 4375.4311939447825, 4310.956890431087, 4311.223979683656, 4338.696291259613, 4353.804078816461, 4369.388285640013, 4315.719478233905, 4319.287317592593, 4319.308910574308, 4323.589259020404, 4334.696571240014, 4103.835846566119, 4134.462519872427, 4250.3836943643, 4257.106007738379, 4316.316636511763, 3788.9431658525145, 3793.3688513938996, 3803.220339830328, 3803.6006219347123, 3804.5434547854156, 5265.552428043621, 5268.209768534713, 5294.894102117974, 5315.946840531584, 5344.305240318772, 4769.761511924425, 4869.4156701195725, 4877.634177436671, 4881.951180488185, 4903.828366007208, 5615.961047206239, 5624.3250809902665, 5652.106544307662, 5655.802046928337, 5676.1769543416385, 4176.4570605821355, 4182.53992060873, 4195.286801243796, 4200.579942005806, 4201.600847919473, 5715.6851475152625, 5815.73829177688, 5828.5628577856405, 5833.250024992528, 5904.822855314347, 4237.936430953539, 4238.8766976751185, 4245.957540424618, 4259.7231179973205, 4282.229198744256, 4342.9782851086175, 4343.9348409773875, 4376.627986621109, 4377.321515165155, 4385.780710964439, 4683.461401938821, 4865.416150062015, 4870.683405578626, 4882.998985208023, 4946.466595355451, 5685.346185188673, 5717.971410142923, 5719.628224165416, 5727.169560413717, 5729.828105156852, 1463.1879306984513, 1463.239115659851, 1464.7436698577706, 1469.3608280398062, 1481.4370539195252, 947.5025611553989, 954.494118117385, 977.4721650308895, 1030.0935177044153, 1040.1730624153815, 5471.480209380103, 5512.834614961557, 5573.860653483695, 5585.413531579207, 5598.188262701886, 5836.0, 5841.591088623755, 5845.649261044351, 5855.121731740188, 5873.794417195463, 5370.516653501204, 5503.752331145108, 5582.581306402036, 5587.8603034923635, 5604.187392828017, 3056.838401407451, 3077.476828939041, 3080.5687203791485, 3083.383323335297, 3091.8608662610236, 5874.941250587525, 6042.486388656926, 6050.485708714808, 6069.0, 6086.2087928569, 5916.2013128228, 5918.674472904032, 5921.496672782861, 5926.762929482772, 5963.254593175853, 6022.247219097613, 6023.819285421444, 6043.9725246707285, 6045.153678485016, 6077.11881777131, 5674.9501342251815, 5728.0, 5792.855178620568, 5817.563682723898, 5843.983825580168, 5710.600257982068, 5722.341930677859, 5722.685252311163, 5729.885402292014, 5763.193152958673, 5788.942110579009, 5999.460048595647, 6017.930855263934, 6024.385368778698, 6071.574989750675, 4207.516135644318, 4245.426867372988, 4252.425922500546, 4272.5727427257025, 4278.272693642026, 5917.319508256433, 5943.970280148572, 5944.464998150187, 5989.460948514655, 6003.5797494174985, 5681.829545113653, 5700.315962084576, 5744.420284421817, 5875.030619947661, 5883.176355310346, 5715.799682066812, 5720.170575266647, 5726.685032323096, 5726.742296596746, 5750.7987220447085, 5461.754221059982, 5717.313922329347, 5719.685417302088, 5736.4263573643975, 5748.48263656273, 5356.544693700923, 5716.971415142898, 5727.513161381163, 5737.713114344183, 5748.6263392881, 5595.0, 5596.384397716167, 5607.691576963306, 5612.579056570692, 5629.831105066854], "value": 4384.245056758625, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.9047314322279, 800.7117437722419, 808.1191501263623, 831.817000259943, 908.1191244492848, 15182.620434489143, 15360.385584576603, 15556.611084722888, 15943.840561594381, 16005.119718415497, 15097.924510377461, 15101.848981510182, 15121.84878151218, 15137.621559461018, 15166.620834479143, 730.9524880882752, 859.8538248497771, 872.1932212703256, 885.1767855894016, 885.5616469847434, 879.6657270237333, 885.3138817416522, 887.5118684723409, 888.2716172738357, 888.4802390601506, 931.222429271559, 931.2410385535801, 933.5612262236738, 933.6078846884325, 934.6495064350869, 759.1004659478508, 891.6388862510694, 921.6451666108529, 923.6490133749165, 924.2282693950526, 683.4498228925713, 684.1619016704547, 684.3806355248508, 684.8630273945231, 684.9794506164823, 15851.0, 15945.043297402193, 15950.042997420076, 15965.201739912958, 15969.520914372588, 14958.177300248482, 15211.467598634106, 15397.692046159014, 15751.054936703835, 15769.763453548207, 13492.392842322119, 13567.796483052762, 13783.931080344635, 14203.78694319586, 14231.928840355835, 4504.459464864204, 4553.726776393394, 4686.133065382915, 4755.904881902377, 4764.904701905977, 84610.57694711549, 86840.13159868383, 87963.56018219868, 88537.55731221303, 88892.0, 11496.597619083292, 12227.694307642203, 12292.385380730928, 12428.813567796491, 12503.687407814878, 22023.66964495534, 22055.779442205694, 22091.66862497064, 22098.0, 22102.226422075473, 9153.130452606993, 9205.309601779893, 9288.90711092894, 9341.953290233505, 9351.672691455902, 5237.476252374809, 5300.840974770763, 5331.573474122061, 5333.466653334635, 5446.373667028263, 3488.1454043758936, 3570.5001299818045, 3595.8741444049333, 3602.729795265313, 3609.4946707460977, 641.5028353026441, 648.1832890557931, 649.4122818848855, 655.5214693273841, 659.0246435275723, 655.2464665634463, 681.7000519771285, 695.6904177641003, 722.2380388689983, 730.5397599512379, 9668.226541876633, 9768.609255629695, 9824.754381140528, 9948.552315145836, 9992.80014399708, 594.0524862843757, 597.2146627185238, 597.4533302028682, 684.736376495053, 699.136566340569, 4690.226112691368, 4692.577668009895, 4748.6675932685075, 4752.1683705351425, 4830.686005409637, 127824.0, 130880.3455982714, 131922.0, 132666.0, 135707.0, 104736.0, 108713.45643271733, 109110.0, 110277.44861275642, 110637.44681276543, 91624.0, 93016.0, 93174.0, 97340.51329743447, 98261.50869245607, 8602.268807151462, 8613.26787223068, 8888.688895888612, 9074.319426043205, 9075.183233509017, 56994.0, 57240.427595725174, 57473.0, 58600.4139958612, 59564.70217648884, 80687.59656201681, 82524.0, 84145.0, 84565.0, 87911.0, 12213.267203967791, 12228.388580570758, 12295.877041229473, 12480.438380273092, 12840.743185136433, 13665.31673416345, 13773.586792396243, 13889.097208681404, 14021.719565608835, 16005.839941600902, 14370.712585748017, 14635.121892686473, 15021.474248401253, 15098.773518397453, 15446.382144714085, 14095.083819551697, 14784.63038424048, 15276.312565934782, 16233.918830405772, 16838.410655626994, 14990.400383984519, 15228.162451065298, 15237.771433428728, 16518.174091295627, 16520.504384868473, 11178.00942567277, 11387.715307117389, 11527.711807204887, 11754.23597466162, 11985.340806255392, 12640.30478323701, 12826.807597885852, 12913.022049415513, 13102.410391532594, 13124.934375328063], "value": 8053.806368297908, "unit": "score"}], "name": "dromaeo_css", "value": 7603.105176684052, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 119.0, 111.0, 111.0, 111.0, 109.0, 109.0, 110.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 117.0, 151.0, 167.0, 115.0, 147.0, 152.0, 117.0, 167.0, 115.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 162.0, 160.0, 161.0, 161.0, 160.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 166.0, 75.0, 75.0, 73.0, 76.0, 161.0, 75.0], "value": 92.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 153.0, 82.0, 83.0, 83.0, 142.0, 83.0, 83.0, 149.0], "value": 102.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0], "value": 116.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 157.0, 156.0, 152.0, 158.0, 155.0, 157.0, 160.0, 156.0, 155.0], "value": 156.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 49.0, 50.0, 50.0, 51.0, 49.0, 49.0, 49.0], "value": 49.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 58.0, 58.0, 58.0, 58.0, 58.0, 58.0, 59.0, 59.0], "value": 58.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 72.0, 72.0, 76.0, 72.0, 72.0, 72.0, 72.0, 75.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 117.0, 117.0, 124.0, 116.0, 115.0, 123.0, 115.0, 169.0, 118.0], "value": 124.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 148.0, 148.0, 149.0, 153.0, 150.0, 153.0, 151.0, 150.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 64.0, 61.0, 61.0, 60.0, 61.0, 60.0, 60.0, 60.0], "value": 61.1, "unit": "score"}], "name": "kraken", "value": 1490.5, "alertThreshold": 2.0}]} 16:10:45 INFO - Return code: 0 16:10:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:10:45 INFO - # TBPL SUCCESS # 16:10:45 INFO - Running post-action listener: _resource_record_post_action 16:10:45 INFO - [mozharness: 2016-09-12 23:10:45.087174Z] Finished run-tests step (success) 16:10:45 INFO - Running post-run listener: _resource_record_post_run 16:10:45 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 325734400; Read time: 12; Write time: 612532 16:10:45 INFO - TinderboxPrint: CPU usage
12.9% 16:10:45 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 16:10:45 INFO - TinderboxPrint: I/O write bytes / time
325,734,400 / 612,532 16:10:45 INFO - TinderboxPrint: CPU idle
8,281.9 (87.0%) 16:10:45 INFO - TinderboxPrint: CPU user
1,181.1 (12.4%) 16:10:45 INFO - TinderboxPrint: Swap in / out
0 / 0 16:10:45 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 79110144; Read time: 0; Write time: 223892 16:10:45 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 246009856; Read time: 12; Write time: 388640 16:10:45 INFO - Running post-run listener: _upload_blobber_files 16:10:45 INFO - Blob upload gear active. 16:10:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:10:45 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 16:10:45 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'] 16:10:45 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 16:10:46 INFO - (blobuploader) - INFO - Open directory for files ... 16:10:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:10:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:10:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:46 INFO - (blobuploader) - INFO - Done attempting. 16:10:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:10:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:10:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:53 INFO - (blobuploader) - INFO - Done attempting. 16:10:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:10:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:10:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:10:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:10:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:10:54 INFO - (blobuploader) - INFO - Done attempting. 16:10:54 INFO - (blobuploader) - INFO - Iteration through files over. 16:10:54 INFO - Return code: 0 16:10:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:10:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:10:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee54765a53e8015edbd25044318ec97b2187bf86cb322cce7ec840e94880b2ab9151d9985f9bffc84e557c7bc54ebab37621c7dd682b4fbc25b2adfa63e57fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c52e46e8833b31b5c22dad42efd79189354bff0b00c38c4d9cfdcd5f45ee371dd4621ceaad06b3a5f71f2bed2ec8e04c70f2a5add4d3622cdf0a79295e39f92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/866509b1556604f3071673cf7940a22ea1eed350d74c221eb46545bad67fa0862ef4cf93f41015fd350315217394ff0f22ca92cac0f15350344ff726e0678763"} 16:10:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:10:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:10:54 INFO - Contents: 16:10:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee54765a53e8015edbd25044318ec97b2187bf86cb322cce7ec840e94880b2ab9151d9985f9bffc84e557c7bc54ebab37621c7dd682b4fbc25b2adfa63e57fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c52e46e8833b31b5c22dad42efd79189354bff0b00c38c4d9cfdcd5f45ee371dd4621ceaad06b3a5f71f2bed2ec8e04c70f2a5add4d3622cdf0a79295e39f92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/866509b1556604f3071673cf7940a22ea1eed350d74c221eb46545bad67fa0862ef4cf93f41015fd350315217394ff0f22ca92cac0f15350344ff726e0678763"} 16:10:54 INFO - Running post-run listener: copy_logs_to_upload_dir 16:10:54 INFO - Copying logs to upload dir... 16:10:54 INFO - mkdir: /builds/slave/test/build/upload/logs 16:10:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1682.753263 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-09-12 16:10:53.663625) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 16:10:53.762236) ========= 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=8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695 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/ee54765a53e8015edbd25044318ec97b2187bf86cb322cce7ec840e94880b2ab9151d9985f9bffc84e557c7bc54ebab37621c7dd682b4fbc25b2adfa63e57fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c52e46e8833b31b5c22dad42efd79189354bff0b00c38c4d9cfdcd5f45ee371dd4621ceaad06b3a5f71f2bed2ec8e04c70f2a5add4d3622cdf0a79295e39f92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/866509b1556604f3071673cf7940a22ea1eed350d74c221eb46545bad67fa0862ef4cf93f41015fd350315217394ff0f22ca92cac0f15350344ff726e0678763"} build_url:https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011380 build_url: 'https://queue.taskcluster.net/v1/task/ArRr8b29QBi9SfhNpWBe4A/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/ee54765a53e8015edbd25044318ec97b2187bf86cb322cce7ec840e94880b2ab9151d9985f9bffc84e557c7bc54ebab37621c7dd682b4fbc25b2adfa63e57fbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0c52e46e8833b31b5c22dad42efd79189354bff0b00c38c4d9cfdcd5f45ee371dd4621ceaad06b3a5f71f2bed2ec8e04c70f2a5add4d3622cdf0a79295e39f92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/866509b1556604f3071673cf7940a22ea1eed350d74c221eb46545bad67fa0862ef4cf93f41015fd350315217394ff0f22ca92cac0f15350344ff726e0678763"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 16:10:53.794660) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 16:10:53.795034) ========= 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=8783db0840cc980e80bb0bd400000236-1473720170.145766-1113697695 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 16:10:53.877148) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 16:10:53.877508) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 16:10:53.877848) ========= ========= Total master_lag: 0.18 =========