builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1474678161.95 results: success (0) buildid: 20160923150429 builduid: 11e45124506d419a88a0a6abc19da4fd revision: 0c2226b65a2f511c90851bb0134d37a6d4dea02b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:21.946282) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:21.946735) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:21.947020) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:21.961473) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:21.961745) ========= 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=fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-23 17:49:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2016-09-23 17:49:22 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.127223 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:22.102681) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:22.102993) ========= 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=fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014681 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:22.152431) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 17:49:22.152799) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 0c2226b65a2f511c90851bb0134d37a6d4dea02b --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-beta --rev 0c2226b65a2f511c90851bb0134d37a6d4dea02b --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=fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-23 17:49:22,242 truncating revision to first 12 chars 2016-09-23 17:49:22,242 Setting DEBUG logging. 2016-09-23 17:49:22,242 attempt 1/10 2016-09-23 17:49:22,242 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/0c2226b65a2f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-23 17:49:23,486 unpacking tar archive at: mozilla-beta-0c2226b65a2f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.617430 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-23 17:49:23.783820) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:23.784143) ========= script_repo_revision: 0c2226b65a2f511c90851bb0134d37a6d4dea02b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:23.784670) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:23.784939) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-23 17:49:23.793776) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-09-23 17:49:23.794042) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:49:23 INFO - MultiFileLogger online at 20160923 17:49:23 in /builds/slave/test 17:49:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:49:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:49:23 INFO - {'append_to_log': False, 17:49:23 INFO - 'base_work_dir': '/builds/slave/test', 17:49:23 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:49:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:49:23 INFO - 'branch': 'Mozilla-Beta', 17:49:23 INFO - 'buildbot_json_path': 'buildprops.json', 17:49:23 INFO - 'config_files': ('talos/linux_config.py',), 17:49:23 INFO - 'default_actions': ('clobber', 17:49:23 INFO - 'read-buildbot-config', 17:49:23 INFO - 'download-and-extract', 17:49:23 INFO - 'populate-webroot', 17:49:23 INFO - 'create-virtualenv', 17:49:23 INFO - 'install', 17:49:23 INFO - 'run-tests'), 17:49:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:49:23 INFO - 'download_minidump_stackwalk': True, 17:49:23 INFO - 'download_symbols': 'ondemand', 17:49:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:49:23 INFO - 'tooltool.py': '/tools/tooltool.py', 17:49:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:49:23 INFO - '/tools/misc-python/virtualenv.py')}, 17:49:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:49:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:49:23 INFO - 'installer_path': 'installer.exe', 17:49:23 INFO - 'log_level': 'info', 17:49:23 INFO - 'log_name': 'talos', 17:49:23 INFO - 'log_to_console': True, 17:49:23 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:49:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:49:23 INFO - 'opt_config_files': (), 17:49:23 INFO - 'pip_index': False, 17:49:23 INFO - 'sps_profile': False, 17:49:23 INFO - 'sps_profile_interval': 0, 17:49:23 INFO - 'suite': 'dromaeojs', 17:49:23 INFO - 'system_bits': '32', 17:49:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:49:23 INFO - 'title': 'talos-linux64-ix-048', 17:49:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:49:23 INFO - 'use_talos_json': True, 17:49:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:49:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:49:23 INFO - 'work_dir': 'build'} 17:49:23 INFO - [mozharness: 2016-09-24 00:49:23.973136Z] Running clobber step. 17:49:23 INFO - Running pre-action listener: _resource_record_pre_action 17:49:23 INFO - Running main action method: clobber 17:49:23 INFO - rmtree: /builds/slave/test/build 17:49:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:49:24 INFO - Running post-action listener: _resource_record_post_action 17:49:24 INFO - [mozharness: 2016-09-24 00:49:24.423251Z] Finished clobber step (success) 17:49:24 INFO - [mozharness: 2016-09-24 00:49:24.423351Z] Running read-buildbot-config step. 17:49:24 INFO - Running pre-action listener: _resource_record_pre_action 17:49:24 INFO - Running main action method: read_buildbot_config 17:49:24 INFO - Using buildbot properties: 17:49:24 INFO - { 17:49:24 INFO - "project": "", 17:49:24 INFO - "product": "firefox", 17:49:24 INFO - "script_repo_revision": "production", 17:49:24 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 17:49:24 INFO - "repository": "", 17:49:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 17:49:24 INFO - "buildid": "20160923150429", 17:49:24 INFO - "pgo_build": "True", 17:49:24 INFO - "basedir": "/builds/slave/test", 17:49:24 INFO - "buildnumber": 399, 17:49:24 INFO - "slavename": "talos-linux64-ix-048", 17:49:24 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:49:24 INFO - "platform": "ubuntu64_hw", 17:49:24 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 17:49:24 INFO - "branch": "mozilla-beta", 17:49:24 INFO - "repo_path": "releases/mozilla-beta", 17:49:24 INFO - "revision": "0c2226b65a2f511c90851bb0134d37a6d4dea02b", 17:49:24 INFO - "stage_platform": "linux64-pgo", 17:49:24 INFO - "builduid": "11e45124506d419a88a0a6abc19da4fd", 17:49:24 INFO - "slavebuilddir": "test" 17:49:24 INFO - } 17:49:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 17:49:24 INFO - Running post-action listener: _resource_record_post_action 17:49:24 INFO - [mozharness: 2016-09-24 00:49:24.425808Z] Finished read-buildbot-config step (success) 17:49:24 INFO - [mozharness: 2016-09-24 00:49:24.425898Z] Running download-and-extract step. 17:49:24 INFO - Running pre-action listener: _resource_record_pre_action 17:49:24 INFO - Running main action method: download_and_extract 17:49:24 INFO - mkdir: /builds/slave/test/build/tests 17:49:24 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')]} 17:49:24 INFO - https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:49:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:49:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:49:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:49:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:49:24 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:49:24 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:49:24 INFO - retry: Failed, sleeping 30 seconds before retrying 17:49:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 17:49:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:49:54 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:49:54 INFO - retry: Failed, sleeping 60 seconds before retrying 17:50:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 17:50:54 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:50:54 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:50:54 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 17:50:54 INFO - Caught exception: HTTP Error 404: Not Found 17:50:54 INFO - Caught exception: HTTP Error 404: Not Found 17:50:54 INFO - Caught exception: HTTP Error 404: Not Found 17:50:54 INFO - trying https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:50:54 INFO - Downloading https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:50:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:50:55 INFO - Downloaded 1327 bytes. 17:50:55 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 17:50:55 INFO - Using the following test package requirements: 17:50:55 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:50:55 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:50:55 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 17:50:55 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:50:55 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 17:50:55 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:50:55 INFO - u'jsshell-linux-x86_64.zip'], 17:50:55 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:50:55 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 17:50:55 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 17:50:55 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:50:55 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 17:50:55 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:50:55 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 17:50:55 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:50:55 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 17:50:55 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 17:50:55 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 17:50:55 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:50: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')]} 17:50:55 INFO - https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:50:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:50:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:50:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:50:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:50:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:50:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:50:55 INFO - retry: Failed, sleeping 30 seconds before retrying 17:51:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:51:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:51:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:51:25 INFO - retry: Failed, sleeping 60 seconds before retrying 17:52:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:52:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:52:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:52:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 17:52:25 INFO - Caught exception: HTTP Error 404: Not Found 17:52:25 INFO - Caught exception: HTTP Error 404: Not Found 17:52:25 INFO - Caught exception: HTTP Error 404: Not Found 17:52:25 INFO - trying https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:52:25 INFO - Downloading https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:52:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:52:26 INFO - Downloaded 21770529 bytes. 17:52:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:52:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:52:27 INFO - Return code: 0 17:52:27 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:52: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')]} 17:52:27 INFO - https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:52:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:52:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:52:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:52:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:52:27 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:52:27 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:52:27 INFO - retry: Failed, sleeping 30 seconds before retrying 17:52:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 17:52:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:52:57 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:52:57 INFO - retry: Failed, sleeping 60 seconds before retrying 17:53:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 17:53:57 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:53:57 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:53:57 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 17:53:57 INFO - Caught exception: HTTP Error 404: Not Found 17:53:57 INFO - Caught exception: HTTP Error 404: Not Found 17:53:57 INFO - Caught exception: HTTP Error 404: Not Found 17:53:57 INFO - trying https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:53:57 INFO - Downloading https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 17:53:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:53:58 INFO - Downloaded 21770529 bytes. 17:53:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:53:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:53:59 INFO - Return code: 0 17:53:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:53:59 INFO - https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:53:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:53:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:53:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:53:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:53:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:53:59 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:53:59 INFO - retry: Failed, sleeping 30 seconds before retrying 17:54:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 17:54:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:54:29 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:54:29 INFO - retry: Failed, sleeping 60 seconds before retrying 17:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 17:55:29 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:55:29 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:55:29 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 17:55:29 INFO - Caught exception: HTTP Error 404: Not Found 17:55:29 INFO - Caught exception: HTTP Error 404: Not Found 17:55:29 INFO - Caught exception: HTTP Error 404: Not Found 17:55:29 INFO - trying https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:55:29 INFO - Downloading https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 17:55:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:55:30 INFO - Downloaded 14413740 bytes. 17:55:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:55:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:55:30 INFO - Return code: 0 17:55:30 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')]} 17:55:30 INFO - https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:55:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:55:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:55:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:55:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:55:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:55:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:55:30 INFO - retry: Failed, sleeping 30 seconds before retrying 17:56:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 17:56:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:56:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:56:00 INFO - retry: Failed, sleeping 60 seconds before retrying 17:57:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 17:57:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:57:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:57:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 17:57:00 INFO - Caught exception: HTTP Error 404: Not Found 17:57:00 INFO - Caught exception: HTTP Error 404: Not Found 17:57:00 INFO - Caught exception: HTTP Error 404: Not Found 17:57:00 INFO - trying https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:57:00 INFO - Downloading https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:57:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:57:01 INFO - Downloaded 56762113 bytes. 17:57:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:57:01 INFO - mkdir: /builds/slave/test/properties 17:57:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:57:01 INFO - Writing to file /builds/slave/test/properties/build_url 17:57:01 INFO - Contents: 17:57:01 INFO - build_url:https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 17:57:02 INFO - Running post-action listener: _resource_record_post_action 17:57:02 INFO - Running post-action listener: set_extra_try_arguments 17:57:02 INFO - [mozharness: 2016-09-24 00:57:02.566715Z] Finished download-and-extract step (success) 17:57:02 INFO - [mozharness: 2016-09-24 00:57:02.566949Z] Running populate-webroot step. 17:57:02 INFO - Running pre-action listener: _resource_record_pre_action 17:57:02 INFO - Running main action method: populate_webroot 17:57:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:57:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:57:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:57:02 INFO - '--tppagecycles', 17:57:02 INFO - '7'], 17:57:02 INFO - 'tests': ['tsvgm']}}, 17:57:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:57:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:57:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:57:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:57:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:57:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:57:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:02 INFO - 'talos_options': ['--disable-e10s'], 17:57:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:57:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:57:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:02 INFO - 'talos_options': ['--disable-e10s'], 17:57:02 INFO - 'tests': ['damp', 'tps']}, 17:57:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:02 INFO - 'tests': ['damp', 'tps']}, 17:57:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:57:02 INFO - 'tests': ['dromaeo_dom']}, 17:57:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:57:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:57:02 INFO - 'tests': ['basic_compositor_video']}, 17:57:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:57:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:57:02 INFO - 'tests': ['a11yr', 17:57:02 INFO - 'ts_paint', 17:57:02 INFO - 'tpaint', 17:57:02 INFO - 'sessionrestore', 17:57:02 INFO - 'sessionrestore_no_auto_restore', 17:57:02 INFO - 'tabpaint']}, 17:57:02 INFO - 'other-e10s': {'tests': ['a11yr', 17:57:02 INFO - 'ts_paint', 17:57:02 INFO - 'tpaint', 17:57:02 INFO - 'sessionrestore', 17:57:02 INFO - 'sessionrestore_no_auto_restore', 17:57:02 INFO - 'tabpaint']}, 17:57:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:57:02 INFO - 'ts_paint', 17:57:02 INFO - 'tpaint', 17:57:02 INFO - 'sessionrestore', 17:57:02 INFO - 'sessionrestore_no_auto_restore', 17:57:02 INFO - 'tabpaint']}, 17:57:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:57:02 INFO - 'ts_paint', 17:57:02 INFO - 'tpaint', 17:57:02 INFO - 'sessionrestore', 17:57:02 INFO - 'sessionrestore_no_auto_restore', 17:57:02 INFO - 'tabpaint']}, 17:57:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:57:02 INFO - 'tests': ['a11yr', 17:57:02 INFO - 'ts_paint', 17:57:02 INFO - 'tpaint', 17:57:02 INFO - 'sessionrestore', 17:57:02 INFO - 'sessionrestore_no_auto_restore', 17:57:02 INFO - 'tabpaint']}, 17:57:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:57:02 INFO - 'tests': ['a11yr', 17:57:02 INFO - 'ts_paint', 17:57:02 INFO - 'tpaint', 17:57:02 INFO - 'sessionrestore', 17:57:02 INFO - 'sessionrestore_no_auto_restore', 17:57:02 INFO - 'tabpaint']}, 17:57:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:57:02 INFO - 'tests': ['tsvgx', 17:57:02 INFO - 'tsvgr_opacity', 17:57:02 INFO - 'tart', 17:57:02 INFO - 'tscrollx', 17:57:02 INFO - 'cart']}, 17:57:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:57:02 INFO - 'tsvgr_opacity', 17:57:02 INFO - 'tart', 17:57:02 INFO - 'tscrollx', 17:57:02 INFO - 'cart']}, 17:57:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:02 INFO - 'talos_options': ['--disable-e10s'], 17:57:02 INFO - 'tests': ['tp5o']}, 17:57:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:02 INFO - 'tests': ['tp5o']}, 17:57:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:02 INFO - 'talos_options': ['--disable-e10s', 17:57:02 INFO - '--xperf_path', 17:57:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:57:02 INFO - 'tests': ['tp5n']}, 17:57:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:57:02 INFO - 'talos_options': ['--xperf_path', 17:57:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:57:02 INFO - 'tests': ['tp5n']}}, 17:57:02 INFO - 'talos.zip': {'path': '', 17:57:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:57:02 INFO - Running post-action listener: _resource_record_post_action 17:57:02 INFO - [mozharness: 2016-09-24 00:57:02.593847Z] Finished populate-webroot step (success) 17:57:02 INFO - [mozharness: 2016-09-24 00:57:02.593936Z] Running create-virtualenv step. 17:57:02 INFO - Running pre-action listener: _resource_record_pre_action 17:57:02 INFO - Running main action method: create_virtualenv 17:57:02 INFO - Creating virtualenv /builds/slave/test/build/venv 17:57:02 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 17:57:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:57:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:57:02 INFO - Using real prefix '/usr' 17:57:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:57:03 INFO - Installing distribute.............................................................................................................................................................................................done. 17:57:05 INFO - Installing pip.................done. 17:57:05 INFO - Return code: 0 17:57:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:57:05 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')]} 17:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:05 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')]} 17:57:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:05 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 0x1007030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c63b0>, '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 0x12c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c7740>, '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': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', '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 17:57:05 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 17:57:05 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 17:57:05 INFO - Using env: {'DISPLAY': ':0', 17:57:05 INFO - 'HOME': '/home/cltbld', 17:57:05 INFO - 'LANG': 'en_US.UTF-8', 17:57:05 INFO - 'LANGUAGE': 'en_US:en', 17:57:05 INFO - 'LOGNAME': 'cltbld', 17:57:05 INFO - 'MAIL': '/var/mail/cltbld', 17:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:05 INFO - 'MOZ_NO_REMOTE': '1', 17:57:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:05 INFO - 'NO_EM_RESTART': '1', 17:57:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:05 INFO - 'PWD': '/builds/slave/test', 17:57:05 INFO - 'SHELL': '/bin/bash', 17:57:05 INFO - 'SHLVL': '1', 17:57:05 INFO - 'TERM': 'linux', 17:57:05 INFO - 'TMOUT': '86400', 17:57:05 INFO - 'USER': 'cltbld', 17:57:05 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', 17:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:05 INFO - '_': '/tools/buildbot/bin/python'} 17:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:05 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 17:57:05 INFO - Cleaning up... 17:57:05 INFO - Return code: 0 17:57:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:57:05 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')]} 17:57:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:05 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')]} 17:57:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:05 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 0x1007030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c63b0>, '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 0x12c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c7740>, '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': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', '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 17:57:05 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 17:57:05 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 17:57:05 INFO - Using env: {'DISPLAY': ':0', 17:57:05 INFO - 'HOME': '/home/cltbld', 17:57:05 INFO - 'LANG': 'en_US.UTF-8', 17:57:05 INFO - 'LANGUAGE': 'en_US:en', 17:57:05 INFO - 'LOGNAME': 'cltbld', 17:57:05 INFO - 'MAIL': '/var/mail/cltbld', 17:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:05 INFO - 'MOZ_NO_REMOTE': '1', 17:57:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:05 INFO - 'NO_EM_RESTART': '1', 17:57:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:05 INFO - 'PWD': '/builds/slave/test', 17:57:05 INFO - 'SHELL': '/bin/bash', 17:57:05 INFO - 'SHLVL': '1', 17:57:05 INFO - 'TERM': 'linux', 17:57:05 INFO - 'TMOUT': '86400', 17:57:05 INFO - 'USER': 'cltbld', 17:57:05 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', 17:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:05 INFO - '_': '/tools/buildbot/bin/python'} 17:57:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:05 INFO - Downloading/unpacking psutil>=3.1.1 17:57:05 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 17:57:05 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 17:57:05 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 17:57:05 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 17:57:05 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 17:57:05 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 17:57:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:57:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:57:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:57:07 INFO - Installing collected packages: psutil 17:57:07 INFO - Running setup.py install for psutil 17:57:07 INFO - building 'psutil._psutil_linux' extension 17:57:07 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 17:57:07 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 17:57:07 INFO - building 'psutil._psutil_posix' extension 17:57:07 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 17:57:07 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 17:57:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:57:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:57:08 INFO - Successfully installed psutil 17:57:08 INFO - Cleaning up... 17:57:08 INFO - Return code: 0 17:57:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1007030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c63b0>, '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 0x12c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c7740>, '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': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', '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 17:57:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:57:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:57:08 INFO - Using env: {'DISPLAY': ':0', 17:57:08 INFO - 'HOME': '/home/cltbld', 17:57:08 INFO - 'LANG': 'en_US.UTF-8', 17:57:08 INFO - 'LANGUAGE': 'en_US:en', 17:57:08 INFO - 'LOGNAME': 'cltbld', 17:57:08 INFO - 'MAIL': '/var/mail/cltbld', 17:57:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:08 INFO - 'MOZ_NO_REMOTE': '1', 17:57:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:08 INFO - 'NO_EM_RESTART': '1', 17:57:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:08 INFO - 'PWD': '/builds/slave/test', 17:57:08 INFO - 'SHELL': '/bin/bash', 17:57:08 INFO - 'SHLVL': '1', 17:57:08 INFO - 'TERM': 'linux', 17:57:08 INFO - 'TMOUT': '86400', 17:57:08 INFO - 'USER': 'cltbld', 17:57:08 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', 17:57:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:08 INFO - '_': '/tools/buildbot/bin/python'} 17:57:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:57:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:57:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:57:10 INFO - Installing collected packages: mozsystemmonitor 17:57:10 INFO - Running setup.py install for mozsystemmonitor 17:57:10 INFO - Successfully installed mozsystemmonitor 17:57:10 INFO - Cleaning up... 17:57:10 INFO - Return code: 0 17:57:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1007030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c63b0>, '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 0x12c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c7740>, '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': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', '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 17:57:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 17:57:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:57:10 INFO - Using env: {'DISPLAY': ':0', 17:57:10 INFO - 'HOME': '/home/cltbld', 17:57:10 INFO - 'LANG': 'en_US.UTF-8', 17:57:10 INFO - 'LANGUAGE': 'en_US:en', 17:57:10 INFO - 'LOGNAME': 'cltbld', 17:57:10 INFO - 'MAIL': '/var/mail/cltbld', 17:57:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:10 INFO - 'MOZ_NO_REMOTE': '1', 17:57:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:10 INFO - 'NO_EM_RESTART': '1', 17:57:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:10 INFO - 'PWD': '/builds/slave/test', 17:57:10 INFO - 'SHELL': '/bin/bash', 17:57:10 INFO - 'SHLVL': '1', 17:57:10 INFO - 'TERM': 'linux', 17:57:10 INFO - 'TMOUT': '86400', 17:57:10 INFO - 'USER': 'cltbld', 17:57:10 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', 17:57:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:10 INFO - '_': '/tools/buildbot/bin/python'} 17:57:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:10 INFO - Downloading/unpacking jsonschema==2.5.1 17:57:10 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 17:57:10 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 17:57:10 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 17:57:10 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 17:57:10 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 17:57:10 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 17:57:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:57:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:57:12 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 17:57:12 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 17:57:12 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 17:57:12 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 17:57:12 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 17:57:12 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 17:57:12 INFO - Downloading functools32-3.2.3-2.tar.gz 17:57:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:57:12 INFO - warning: no files found matching '*.txt' 17:57:12 INFO - no previously-included directories found matching 'build' 17:57:12 INFO - no previously-included directories found matching 'dist' 17:57:12 INFO - no previously-included directories found matching '.git*' 17:57:12 INFO - Installing collected packages: jsonschema, functools32 17:57:12 INFO - Running setup.py install for functools32 17:57:12 INFO - warning: no files found matching '*.txt' 17:57:12 INFO - no previously-included directories found matching 'build' 17:57:12 INFO - no previously-included directories found matching 'dist' 17:57:12 INFO - no previously-included directories found matching '.git*' 17:57:12 INFO - Successfully installed jsonschema functools32 17:57:12 INFO - Cleaning up... 17:57:12 INFO - Return code: 0 17:57:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:57:12 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')]} 17:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:12 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')]} 17:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:12 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 0x1007030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c63b0>, '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 0x12c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c7740>, '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': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', '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 17:57:12 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 17:57:12 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 17:57:12 INFO - Using env: {'DISPLAY': ':0', 17:57:12 INFO - 'HOME': '/home/cltbld', 17:57:12 INFO - 'LANG': 'en_US.UTF-8', 17:57:12 INFO - 'LANGUAGE': 'en_US:en', 17:57:12 INFO - 'LOGNAME': 'cltbld', 17:57:12 INFO - 'MAIL': '/var/mail/cltbld', 17:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:12 INFO - 'MOZ_NO_REMOTE': '1', 17:57:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:12 INFO - 'NO_EM_RESTART': '1', 17:57:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:12 INFO - 'PWD': '/builds/slave/test', 17:57:12 INFO - 'SHELL': '/bin/bash', 17:57:12 INFO - 'SHLVL': '1', 17:57:12 INFO - 'TERM': 'linux', 17:57:12 INFO - 'TMOUT': '86400', 17:57:12 INFO - 'USER': 'cltbld', 17:57:12 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', 17:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:12 INFO - '_': '/tools/buildbot/bin/python'} 17:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:57:13 INFO - Cleaning up... 17:57:13 INFO - Return code: 0 17:57:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1007030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c63b0>, '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 0x12c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c7740>, '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': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', '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 17:57:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:57:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:57:13 INFO - Using env: {'DISPLAY': ':0', 17:57:13 INFO - 'HOME': '/home/cltbld', 17:57:13 INFO - 'LANG': 'en_US.UTF-8', 17:57:13 INFO - 'LANGUAGE': 'en_US:en', 17:57:13 INFO - 'LOGNAME': 'cltbld', 17:57:13 INFO - 'MAIL': '/var/mail/cltbld', 17:57:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:13 INFO - 'MOZ_NO_REMOTE': '1', 17:57:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:13 INFO - 'NO_EM_RESTART': '1', 17:57:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:13 INFO - 'PWD': '/builds/slave/test', 17:57:13 INFO - 'SHELL': '/bin/bash', 17:57:13 INFO - 'SHLVL': '1', 17:57:13 INFO - 'TERM': 'linux', 17:57:13 INFO - 'TMOUT': '86400', 17:57:13 INFO - 'USER': 'cltbld', 17:57:13 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', 17:57:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:13 INFO - '_': '/tools/buildbot/bin/python'} 17:57:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:13 INFO - Downloading/unpacking blobuploader==1.2.4 17:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:15 INFO - Downloading blobuploader-1.2.4.tar.gz 17:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:57:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:57:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:15 INFO - Downloading docopt-0.6.1.tar.gz 17:57:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:57:16 INFO - Installing collected packages: blobuploader, requests, docopt 17:57:16 INFO - Running setup.py install for blobuploader 17:57:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:57:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:57:16 INFO - Running setup.py install for requests 17:57:16 INFO - Running setup.py install for docopt 17:57:16 INFO - Successfully installed blobuploader requests docopt 17:57:16 INFO - Cleaning up... 17:57:16 INFO - Return code: 0 17:57:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:16 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 0x1007030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c63b0>, '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 0x12c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c7740>, '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': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', '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 17:57:16 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 17:57:16 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 17:57:16 INFO - Using env: {'DISPLAY': ':0', 17:57:16 INFO - 'HOME': '/home/cltbld', 17:57:16 INFO - 'LANG': 'en_US.UTF-8', 17:57:16 INFO - 'LANGUAGE': 'en_US:en', 17:57:16 INFO - 'LOGNAME': 'cltbld', 17:57:16 INFO - 'MAIL': '/var/mail/cltbld', 17:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:16 INFO - 'MOZ_NO_REMOTE': '1', 17:57:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:16 INFO - 'NO_EM_RESTART': '1', 17:57:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:16 INFO - 'PWD': '/builds/slave/test', 17:57:16 INFO - 'SHELL': '/bin/bash', 17:57:16 INFO - 'SHLVL': '1', 17:57:16 INFO - 'TERM': 'linux', 17:57:16 INFO - 'TMOUT': '86400', 17:57:16 INFO - 'USER': 'cltbld', 17:57:16 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', 17:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:16 INFO - '_': '/tools/buildbot/bin/python'} 17:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:57:16 INFO - Running setup.py (path:/tmp/pip-cJo9s5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:57:16 INFO - Running setup.py (path:/tmp/pip-_t_4pT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:57:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:57:16 INFO - Running setup.py (path:/tmp/pip-Z6fuJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:57:17 INFO - Running setup.py (path:/tmp/pip-awFGtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:57:17 INFO - Running setup.py (path:/tmp/pip-MmPoLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:57:17 INFO - Running setup.py (path:/tmp/pip-lqAz4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:57:17 INFO - Running setup.py (path:/tmp/pip-Rj0HLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:57:17 INFO - Running setup.py (path:/tmp/pip-YzGBO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:57:17 INFO - Running setup.py (path:/tmp/pip-Q09rtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:57:17 INFO - Running setup.py (path:/tmp/pip-Lh9joE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:57:17 INFO - Running setup.py (path:/tmp/pip-4Owqia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:57:17 INFO - Running setup.py (path:/tmp/pip-EDMxu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:57:17 INFO - Running setup.py (path:/tmp/pip-uLC1tJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:57:17 INFO - Running setup.py (path:/tmp/pip-M0gvYb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:17 INFO - Running setup.py (path:/tmp/pip-l_LKRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:57:17 INFO - Running setup.py (path:/tmp/pip-_gtZ3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:57:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:57:17 INFO - Running setup.py (path:/tmp/pip-866fC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:57:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:57:18 INFO - Running setup.py install for manifestparser 17:57:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:57:18 INFO - Running setup.py install for mozcrash 17:57:18 INFO - Running setup.py install for mozdebug 17:57:18 INFO - Running setup.py install for mozdevice 17:57:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:57:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:57:18 INFO - Running setup.py install for mozfile 17:57:18 INFO - Running setup.py install for mozhttpd 17:57:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:57:18 INFO - Running setup.py install for mozinfo 17:57:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:57:18 INFO - Running setup.py install for mozInstall 17:57:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:57:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:57:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:57:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:57:18 INFO - Running setup.py install for mozleak 17:57:18 INFO - Running setup.py install for mozlog 17:57:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:57:19 INFO - Running setup.py install for moznetwork 17:57:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:57:19 INFO - Running setup.py install for mozprocess 17:57:19 INFO - Running setup.py install for mozprofile 17:57:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:57:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:57:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:57:19 INFO - Running setup.py install for mozrunner 17:57:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:57:19 INFO - Running setup.py install for mozscreenshot 17:57:19 INFO - Running setup.py install for moztest 17:57:19 INFO - Running setup.py install for mozversion 17:57:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:57:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:57:19 INFO - Cleaning up... 17:57:19 INFO - Return code: 0 17:57:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:57:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:19 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 0x1007030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c63b0>, '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 0x12c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c7740>, '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': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', '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 17:57:19 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 17:57:19 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 17:57:19 INFO - Using env: {'DISPLAY': ':0', 17:57:19 INFO - 'HOME': '/home/cltbld', 17:57:19 INFO - 'LANG': 'en_US.UTF-8', 17:57:19 INFO - 'LANGUAGE': 'en_US:en', 17:57:19 INFO - 'LOGNAME': 'cltbld', 17:57:19 INFO - 'MAIL': '/var/mail/cltbld', 17:57:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:19 INFO - 'MOZ_NO_REMOTE': '1', 17:57:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:19 INFO - 'NO_EM_RESTART': '1', 17:57:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:19 INFO - 'PWD': '/builds/slave/test', 17:57:19 INFO - 'SHELL': '/bin/bash', 17:57:19 INFO - 'SHLVL': '1', 17:57:19 INFO - 'TERM': 'linux', 17:57:19 INFO - 'TMOUT': '86400', 17:57:19 INFO - 'USER': 'cltbld', 17:57:19 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', 17:57:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:19 INFO - '_': '/tools/buildbot/bin/python'} 17:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:57:20 INFO - Running setup.py (path:/tmp/pip-A6ARX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:57:20 INFO - Running setup.py (path:/tmp/pip-YbJ4wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:57:20 INFO - Running setup.py (path:/tmp/pip-5FTGDM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:57:20 INFO - Running setup.py (path:/tmp/pip-7r2Fw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:57:20 INFO - Running setup.py (path:/tmp/pip-8goaSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:57:20 INFO - Running setup.py (path:/tmp/pip-MjNVNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:57:20 INFO - Running setup.py (path:/tmp/pip-L_Dx4K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:57:20 INFO - Running setup.py (path:/tmp/pip-70_rqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:57:20 INFO - Running setup.py (path:/tmp/pip-aK73nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:57:20 INFO - Running setup.py (path:/tmp/pip-XUsb0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:57:20 INFO - Running setup.py (path:/tmp/pip-EbYp48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:57:20 INFO - Running setup.py (path:/tmp/pip-jpu8Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:57:20 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)) 17:57:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:57:20 INFO - Running setup.py (path:/tmp/pip-BcnhiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:57:21 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)) 17:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:57:21 INFO - Running setup.py (path:/tmp/pip-fQGwJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:57:21 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)) 17:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:21 INFO - Running setup.py (path:/tmp/pip-JN76hX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:57:21 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)) 17:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:57:21 INFO - Running setup.py (path:/tmp/pip-jrS1z9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:57:21 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)) 17:57:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:57:21 INFO - Running setup.py (path:/tmp/pip-RAtY0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:57:21 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)) 17:57:21 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)) 17:57:21 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)) 17:57:21 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)) 17:57:21 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)) 17:57:21 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)) 17:57:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:57:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:57:23 INFO - Downloading blessings-1.6.tar.gz 17:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:57:23 INFO - Installing collected packages: blessings 17:57:23 INFO - Running setup.py install for blessings 17:57:23 INFO - Successfully installed blessings 17:57:23 INFO - Cleaning up... 17:57:23 INFO - Return code: 0 17:57:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:57:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:57:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:57:23 INFO - Reading from file tmpfile_stdout 17:57:23 INFO - Current package versions: 17:57:23 INFO - argparse == 1.2.1 17:57:23 INFO - blessings == 1.6 17:57:23 INFO - blobuploader == 1.2.4 17:57:23 INFO - docopt == 0.6.1 17:57:23 INFO - functools32 == 3.2.3-2 17:57:23 INFO - jsonschema == 2.5.1 17:57:23 INFO - manifestparser == 1.1 17:57:23 INFO - mozInstall == 1.12 17:57:23 INFO - mozcrash == 0.17 17:57:23 INFO - mozdebug == 0.1 17:57:23 INFO - mozdevice == 0.48 17:57:23 INFO - mozfile == 1.2 17:57:23 INFO - mozhttpd == 0.7 17:57:23 INFO - mozinfo == 0.9 17:57:23 INFO - mozleak == 0.1 17:57:23 INFO - mozlog == 3.3 17:57:23 INFO - moznetwork == 0.27 17:57:23 INFO - mozprocess == 0.23 17:57:23 INFO - mozprofile == 0.28 17:57:23 INFO - mozrunner == 6.12 17:57:23 INFO - mozscreenshot == 0.1 17:57:23 INFO - mozsystemmonitor == 0.3 17:57:23 INFO - moztest == 0.7 17:57:23 INFO - mozversion == 1.4 17:57:23 INFO - psutil == 3.1.1 17:57:23 INFO - requests == 1.2.3 17:57:23 INFO - wsgiref == 0.1.2 17:57:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:57: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')]} 17:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57: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')]} 17:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:23 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 0x1007030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c63b0>, '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 0x12c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c7740>, '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': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', '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 17:57:23 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 17:57:23 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 17:57:23 INFO - Using env: {'DISPLAY': ':0', 17:57:23 INFO - 'HOME': '/home/cltbld', 17:57:23 INFO - 'LANG': 'en_US.UTF-8', 17:57:23 INFO - 'LANGUAGE': 'en_US:en', 17:57:23 INFO - 'LOGNAME': 'cltbld', 17:57:23 INFO - 'MAIL': '/var/mail/cltbld', 17:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:23 INFO - 'MOZ_NO_REMOTE': '1', 17:57:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:23 INFO - 'NO_EM_RESTART': '1', 17:57:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:23 INFO - 'PWD': '/builds/slave/test', 17:57:23 INFO - 'SHELL': '/bin/bash', 17:57:23 INFO - 'SHLVL': '1', 17:57:23 INFO - 'TERM': 'linux', 17:57:23 INFO - 'TMOUT': '86400', 17:57:23 INFO - 'USER': 'cltbld', 17:57:23 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', 17:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:23 INFO - '_': '/tools/buildbot/bin/python'} 17:57:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:23 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)) 17:57:23 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)) 17:57:23 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)) 17:57:23 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)) 17:57:23 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)) 17:57:23 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)) 17:57:23 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)) 17:57:23 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)) 17:57:23 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)) 17:57:23 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)) 17:57:23 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)) 17:57:23 INFO - Cleaning up... 17:57:23 INFO - Return code: 0 17:57:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:57: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')]} 17:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:57:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57: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')]} 17:57:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:57:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:57:23 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 0x1007030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x116a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12c8180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12c63b0>, '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 0x12c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c7740>, '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': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', '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 17:57:23 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 17:57:23 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 17:57:23 INFO - Using env: {'DISPLAY': ':0', 17:57:23 INFO - 'HOME': '/home/cltbld', 17:57:23 INFO - 'LANG': 'en_US.UTF-8', 17:57:23 INFO - 'LANGUAGE': 'en_US:en', 17:57:23 INFO - 'LOGNAME': 'cltbld', 17:57:23 INFO - 'MAIL': '/var/mail/cltbld', 17:57:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:23 INFO - 'MOZ_NO_REMOTE': '1', 17:57:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:23 INFO - 'NO_EM_RESTART': '1', 17:57:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:23 INFO - 'PWD': '/builds/slave/test', 17:57:23 INFO - 'SHELL': '/bin/bash', 17:57:23 INFO - 'SHLVL': '1', 17:57:23 INFO - 'TERM': 'linux', 17:57:23 INFO - 'TMOUT': '86400', 17:57:23 INFO - 'USER': 'cltbld', 17:57:23 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', 17:57:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:23 INFO - '_': '/tools/buildbot/bin/python'} 17:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:57:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:57:24 INFO - Cleaning up... 17:57:24 INFO - Return code: 0 17:57:24 INFO - Running post-action listener: _resource_record_post_action 17:57:24 INFO - Running post-action listener: _start_resource_monitoring 17:57:24 INFO - Starting resource monitoring. 17:57:24 INFO - [mozharness: 2016-09-24 00:57:24.142400Z] Finished create-virtualenv step (success) 17:57:24 INFO - [mozharness: 2016-09-24 00:57:24.142831Z] Running install step. 17:57:24 INFO - Running pre-action listener: _resource_record_pre_action 17:57:24 INFO - Running main action method: install 17:57:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:57:24 INFO - Reading from file tmpfile_stdout 17:57:24 INFO - Detecting whether we're running mozinstall >=1.0... 17:57:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:57:24 INFO - Reading from file tmpfile_stdout 17:57:24 INFO - Output received: 17:57:24 INFO - Usage: mozinstall [options] installer 17:57:24 INFO - Options: 17:57:24 INFO - -h, --help show this help message and exit 17:57:24 INFO - -d DEST, --destination=DEST 17:57:24 INFO - Directory to install application into. [default: 17:57:24 INFO - "/builds/slave/test"] 17:57:24 INFO - --app=APP Application being installed. [default: firefox] 17:57:24 INFO - mkdir: /builds/slave/test/build/application 17:57:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:57:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:57:38 INFO - Reading from file tmpfile_stdout 17:57:38 INFO - Output received: 17:57:38 INFO - /builds/slave/test/build/application/firefox/firefox 17:57:38 INFO - Running post-action listener: _resource_record_post_action 17:57:38 INFO - [mozharness: 2016-09-24 00:57:38.879658Z] Finished install step (success) 17:57:38 INFO - [mozharness: 2016-09-24 00:57:38.879936Z] Running run-tests step. 17:57:38 INFO - Running pre-action listener: _resource_record_pre_action 17:57:38 INFO - Running main action method: run_tests 17:57:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:57:38 INFO - Python 2.7.3 17:57:38 INFO - Return code: 0 17:57:38 INFO - grabbing minidump binary from tooltool 17:57: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')]} 17:57:38 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 0x12c63b0>, '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 0x12c72b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x12c7740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:57:38 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 17:57:38 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 17:57:38 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 17:57:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:57:39 INFO - Return code: 0 17:57:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:57:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:57:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:57:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:57:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:57:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 17:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 17:57:39 INFO - Using env: {'DISPLAY': ':0', 17:57:39 INFO - 'HOME': '/home/cltbld', 17:57:39 INFO - 'LANG': 'en_US.UTF-8', 17:57:39 INFO - 'LANGUAGE': 'en_US:en', 17:57:39 INFO - 'LOGNAME': 'cltbld', 17:57:39 INFO - 'MAIL': '/var/mail/cltbld', 17:57:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:57:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:57:39 INFO - 'MOZ_NO_REMOTE': '1', 17:57:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:57:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:57:39 INFO - 'NO_EM_RESTART': '1', 17:57:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:57:39 INFO - 'PWD': '/builds/slave/test', 17:57:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:57:39 INFO - 'SHELL': '/bin/bash', 17:57:39 INFO - 'SHLVL': '1', 17:57:39 INFO - 'TERM': 'linux', 17:57:39 INFO - 'TMOUT': '86400', 17:57:39 INFO - 'USER': 'cltbld', 17:57:39 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429', 17:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:57:39 INFO - '_': '/tools/buildbot/bin/python'} 17:57:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 17:57:39 INFO - mozversion application_buildid: 20160923150429 17:57:39 INFO - mozversion application_changeset: 0c2226b65a2f511c90851bb0134d37a6d4dea02b 17:57:39 INFO - mozversion application_display_name: Firefox 17:57:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:57:39 INFO - mozversion application_name: Firefox 17:57:39 INFO - mozversion application_remotingname: firefox 17:57:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:57:39 INFO - mozversion application_vendor: Mozilla 17:57:39 INFO - mozversion application_version: 50.0 17:57:39 INFO - mozversion platform_buildid: 20160923150429 17:57:39 INFO - mozversion platform_changeset: 0c2226b65a2f511c90851bb0134d37a6d4dea02b 17:57:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:57:39 INFO - mozversion platform_version: 50.0 17:57:39 INFO - using testdate: 1474678659 17:57:39 INFO - actual date: 1474678659 17:57:39 INFO - starting webserver on 'localhost:42734' 17:57:39 INFO - SUITE-START | Running 2 tests 17:57:39 INFO - TEST-START | dromaeo_css 17:57:39 INFO - operating with platform_type : linux_ 17:57:39 INFO - Initialising browser for dromaeo_css test... 17:57:39 INFO - TEST-INFO | started process 5481 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnMQblU/profile http://localhost:42734/getInfo.html) 17:57:43 INFO - PROCESS | 5481 | __metrics Screen width/height:1600/1200 17:57:43 INFO - PROCESS | 5481 | colorDepth:24 17:57:43 INFO - PROCESS | 5481 | Browser inner width/height: 1024/697 17:57:43 INFO - PROCESS | 5481 | __metrics 17:57:44 INFO - PROCESS | 5481 | 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" 17:57:44 INFO - PROCESS | 5481 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:57:45 INFO - TEST-INFO | 5481: exit 0 17:57:45 INFO - Browser initialized. 17:57:45 INFO - Running cycle 1/1 for dromaeo_css test... 17:57:45 INFO - TEST-INFO | started process 5569 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnMQblU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:57:57 INFO - PROCESS | 5569 | RSS: Main: 144076800 17:57:57 INFO - PROCESS | 5569 | 17:58:48 INFO - PROCESS | 5569 | 1474678728121 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] 18:00:57 INFO - PROCESS | 5569 | Cycle 1(1): loaded http://localhost:42734/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42734/tests/dromaeo/cssquery-ext.html) 18:00:58 INFO - PROCESS | 5569 | RSS: Main: 218374144 18:00:58 INFO - PROCESS | 5569 | 18:04:00 INFO - PROCESS | 5569 | Cycle 1(1): loaded http://localhost:42734/tests/dromaeo/cssquery-ext.html (next: http://localhost:42734/tests/dromaeo/cssquery-jquery.html) 18:04:01 INFO - PROCESS | 5569 | RSS: Main: 231800832 18:04:01 INFO - PROCESS | 5569 | 18:07:02 INFO - PROCESS | 5569 | Cycle 1(1): loaded http://localhost:42734/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42734/tests/dromaeo/cssquery-mootools.html) 18:07:03 INFO - PROCESS | 5569 | RSS: Main: 200257536 18:07:03 INFO - PROCESS | 5569 | 18:10:05 INFO - PROCESS | 5569 | Cycle 1(1): loaded http://localhost:42734/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42734/tests/dromaeo/cssquery-prototype.html) 18:10:05 INFO - PROCESS | 5569 | RSS: Main: 176799744 18:10:05 INFO - PROCESS | 5569 | 18:13:08 INFO - PROCESS | 5569 | Cycle 1(1): loaded http://localhost:42734/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42734/tests/dromaeo/cssquery-yui.html) 18:13:09 INFO - PROCESS | 5569 | RSS: Main: 176283648 18:13:09 INFO - PROCESS | 5569 | 18:16:11 INFO - PROCESS | 5569 | Cycle 1(1): loaded http://localhost:42734/tests/dromaeo/cssquery-yui.html (next: http://localhost:42734/tests/dromaeo/cssquery-dojo.html) 18:16:11 INFO - PROCESS | 5569 | RSS: Main: 259137536 18:16:11 INFO - PROCESS | 5569 | 18:16:11 INFO - PROCESS | 5569 | __start_tp_report 18:16:11 INFO - PROCESS | 5569 | _x_x_mozilla_page_load 18:16:11 INFO - PROCESS | 5569 | _x_x_mozilla_page_load_details 18:16:11 INFO - PROCESS | 5569 | |i|pagename|runs| 18:16:11 INFO - PROCESS | 5569 | |0;dojo.html;3183.7771356005073;3183.7930534515253;3194.440972829754;3198.968010319896;3240.1089700332404;5464.808731694391;5562.86092847679;5575.665460072399;5575.888482230358;5612.354579223385;5676.120201368784;5753.482186603205;5756;5760.827175184741;5788.073908174682;5026.447090820006;5150.819721309755;5182.8704282393055;5240.68555886648;5259.237410575464;5074.746262686869;5165.483451654842;5209.426963034063;5226.92159617606;5230.084735171349;5084.720340381277;5132.384113906336;5145.151050076733;5156.561692256144;5204.219367094928;5245.291885595453;5255.947440525584;5260.132078207092;5267.236250743659;5296.867578310535;4936.4076310842565;5004.374453193351;5054.974725126351;5113.309703190059;5142.408623008365;5489.039418101816;5505.724713764296;5517.503424691797;5527.585431092644;5527.640703354309;6031.24609423822;6039.486643635296;6041.667708276043;6041.667708276043;6050;5848.005839007336;6006.8498287542725;6014.488768454687;6053.424924632153;6063.211782468251;5654.349749778746;5667.489925906606;5672.688002159838;5673.631213971079;5692.686902220376;6180.721867515973;6187.969060154671;6189.84525386869;6192.97815860378;6199.628022318675;5713.600047996601;5864.560157988168;5928.466438020599;5929.762809487658;6026.306974697878;6069.51443884497;6074.240719910012;6078.97901778173;6101.481374083164;6116.388361163937;6026.728797204137;6031.2159419276095;6035.818925432244;6036.547258955596;6052.788152414644;5783.913241301384;5984.611000284967;6028.758849646053;6029.8191054268445;6119.143319935123;4626.768661566908;4654.232051711429;4659.044895796354;4659.510751371125;4694.507076756891;4152.045029643199;4164.312888373384;4167.458230430025;4181.832726690959;4189.958100419018;5604.439556044363;5727.255456790591;5799;5816.796412125555;5868.2664666916635;6169.722362493699;6171.907421388682;6173.290071641729;6176.320604733476;6183.721732522047;5814.9709251454315;5819.854503637358;5822.184894114827;5823.417658234227;5828.912566311508;5166.741662916839;5206.869828254248;5230.555402790806;5231.607629427732;5302.496262855023;6276.340984196685;6292.433680968643;6293.590916590407;6295.842603934847;6301.275353334425;6041;6050.031994880798;6105.633661980295;6108.083787431743;6109.236345456818;6094.299155597163;6193.81418557444;6202.689865506797;6231.034189700604;6344.492440604648;6003.87992240144;6004.039353703386;6005.099235114768;6006.579539432198;6055.666938318434;6143.877122457615;6160.568760186744;6162.137300777805;6167.105769663463;6168.814935551941;5799.478046975623;5950.047992321381;5979.1928089707335;5984.072468767348;6071.635701857903;5961.493273071837;5964.612300200473;5965;6004.759809607567;6007.279126504847;5911.733971971357;5955.344912059583;5968.194293770285;5968.612040217372;5970.313413957277;5828.184054232326;5870.442307980735;5931.881362372815;5934.6142500737315;5991.2510936132985;5823.970880145572;5837.387074357302;5839;5841.50347220491;5867.911981320369 18:16:11 INFO - PROCESS | 5569 | |1;ext.html;603.4990957505271;634.9619022858628;661.4774328280658;669.2738378858937;675.1122274209414;35450.64549354509;38873.22253554927;38887.22225555487;38889.41665875014;38922.41616375757;38833.417498737545;47975.28037079447;48851.267230991565;48978.51021489784;51847.222291665654;9173.128552787475;9304.767380815467;9312.208462280714;9318.72043838686;9330.860037099448;8448.957755211215;8698.521581313025;9231.076892310748;9330.486823224719;9331.860022099674;11237.381943993078;11529.596464123757;11535.134864885125;11839.64481065565;11869.7626047479;11284.153688473356;11339.943300283447;11365.943170284138;11383.373914434702;11386.54453821846;11223.66329010131;11284.717882052932;11287.548498060069;11302.547898084067;11304.547818087267;46123.53876461226;46307;46416;46496.53503464956;46538;3911.198204368098;3922.941155882664;3981.980090099531;4127.422160897477;4606.56237657422;34346.31307373864;34639.65360346389;34706.65293347085;35097.82451087728;36987.81506092479;4589.954100458986;4625.236835922069;4680.953190468086;4703.882402939919;4783.4977327380475;371437;372300;372626;372948.1352593247;373421;62204;62352.376476235564;62378;62433.68783156055;62440.68779656073;39877.20245595072;40677.18645627071;40848.387274190914;42410.57589424066;42499.575004249564;17912.641747164984;17945.102744862965;18039.549011274823;18084.276628934695;18098.185581648857;13852.376643051088;13945.721085578232;13946.302684865715;13947.16317020982;13965.58103256904;18473.44579662612;19665.901670491556;20599.279025234046;20694.275700350416;20719.171233150806;7342.743003994941;7397.741079062207;7467.215942326086;7512.286332798375;7683.346915512244;959.4435227567993;1088.4992903264429;1096.331237944853;1132.456420917964;1154.3997121496775;34028.82985585056;34280.14299642529;34364.48453273203;34865.47701784475;35150.472742908874;1125.9605913792977;1147.2829481574017;1166.2302880099094;1166.4750862112085;1178.2164860367932;17312.740308895376;18147.364842230458;18271.543211419554;18302.725459118123;18418.17118229683;665217;669936;670779;671071;674531.0187592539;62863.685681571296;62996.05505917415;63076.684616576626;63107.05339419912;63461.68269158625;1299.636101891472;1405.1358414575168;1416.7249475471951;1426.9143851369158;1444.6460617148866;32984.34031319312;34917.30165396729;35237.64762352344;35417.64582354143;35485.64514354823;53796.73101634467;54411;54484;54494.72752636211;54494.7275263637;416598;416765;417362;418397;500781;30894;31666.208344791565;31666.525002125447;32579.837100814344;32655.836720816245;51241;52284.47715522796;52358;52941.20588191103;53504.46495534995;43521;44514;44625.33062004137;45082.3237651442;46166.30750538812;42514.149717006105;42631;42919;44592;44657.09728867613;44133.55866441423;44242.77878610586;44829.55170448254;45446.54553454423;45471;35307.64692353044;39453;40104.197916042096;40511.1897762049;40520.189596208504;29894.551581725857;30037.446817019925;30337;30663.386732265677;30967 18:16:11 INFO - PROCESS | 5569 | |2;jquery.html;421.96624270058396;429.5230112241405;438.0909612553951;439.15462734236604;446.8748750349901;18932.148053337598;19770.505737356554;20704.171833126675;20733.68899466509;20931.895340523315;19273.91617341222;19890.303839365624;20173.495662608442;20512.28207012755;20557;5963.373845746189;6193.7832175873855;6197.628142311476;6198.690065496708;6201.255849298091;6027.095935609672;6037.728302226388;6038.33578306386;6047.244094488189;6094.756209751605;6055.939440605582;6056.031035034395;6059.606125601844;6063.6361818290825;6066.484348830355;5975.402459754033;6031.6984150792505;6063.363346848561;6069.78755743549;6075.665838378887;5248.081585722483;5456.427075157091;5470.480304371078;5471.480209380103;5475.589330800206;24194.274171774876;24199.03203871843;24735.50528989428;24943.750562494504;24950.50098998028;2521.773040426352;2532.202356257773;2695.8517281549507;2849.786266030056;2864.240976141324;15153.924230378887;15307.46423875159;15315.31081101353;15371.231438428034;15683.451079212287;2065.9380218593465;2067.157806946126;2216.468047668564;2278.1343089626002;2305.5043165719285;65192.674036629985;70347;84050.15949840484;86808.56595717045;91799.54100229456;11763;11784.116191285688;11990.460429280529;12079.818802717966;12237.694057648452;17276.568085797957;17294.913525432294;17314.65370692579;17319.307227711004;17324.653506930044;8268.09051004389;8358.707445239388;8482.745517634481;8524.488530688179;8586;7808.414368922354;7891.921080789119;7923.7226697065325;8069.394795390252;8100.675972961016;5083.923741143886;5234.345706786652;5256.342957130359;5289.629725919149;5293.126634105406;2089.5194105355854;2096.4234835420075;2138.700581918533;2170.3814412892248;2178.945526361854;934.9579268932914;987.7579992901785;1032.7521394865178;1089.8201796703613;1110.3393480878865;17965.191566379544;19249.422517324503;20158.193672253237;20817.888317589128;20909.268175614085;1001.84972254161;1011.7420057885192;1048.4024106259512;1061.9681409557725;1081.4862940103387;9257.703921451002;10986;11509.48207330672;11625.24435911663;11703.063754899758;540252.2565485863;544280;548327;565973;566270;100008;101636.49181754192;102501.48749256205;102617;105717.47141264398;994.6667866264737;999.3204620857887;1014.7463134216594;1025.707673313117;1026.4457193115634;17436.302547897943;17857.196426160594;18214.635707286045;18321.267149313877;18575.72136417982;42944.35583466313;43880.122397552506;44140.11719765651;44589;44709.329360058975;57610.423895762186;59335;62520;63194.368056318846;64041;8869.379143460154;9122.224610908148;9238.907610923805;9348.252139828934;9562.235021198148;13797.448102075805;14057.226852522801;14081.834433580567;14401.63995900111;14614.123152610877;11862.169648124549;12113.030957523553;12224.694382640506;12359.258444493;12435.253884766942;11748.706282342668;12013.879861201276;12274.570390036306;12472.625821225378;12767.042471814466;12083.879161208275;12377.814332784836;12422.316772577233;12530.498780048696;12765.297908615115;9108.681196158102;9195.540222988957;9308.581113850028;9377.624895004123;9668.984756600737;10637.9468102659;10822.710437459737;11603.36181510025;11912.583059592873;12337.691557711129 18:16:11 INFO - PROCESS | 5569 | |3;mootools.html;1378.6966867289204;1502.2939218567276;1548.4735190035385;1699.3797263998636;1835.45853973078;8549.2733117685;8896.199342059213;8963.37256392052;9200.815983680322;9229.630814767417;9281.675141370053;9388.342816002883;9392.342536022481;9398.436093834374;9399.295052871028;2472.5425796227664;2487.092211342856;2496.5131799299143;2496.8127390445698;2497.4131079196372;2434.926952191437;2521.1428114441087;2522.608995605675;2524.469861329114;2526.7852232560163;2384.2966324934187;2427.8543287402813;2499.250224932522;2499.787518060958;2500.4874000829786;2466.05057053035;2492.7756501914823;2493.8628375439343;2498.95002099957;2498.9754200777684;2297.7702229776887;2345.6598793175067;2350.200931683236;2351.7648235176516;2459.348272707734;9070.047644997312;9407.952960235223;9457.716268511957;9503.809923801486;9513.048695130501;5231.633785635007;5374.838754837361;5437.972810135963;5447.836564903059;5452.209429632681;3494.493298471733;3519.683228509421;3537.1510837398837;3555.075680323109;3598.064503229179;1244.564402459141;1270.1109223543465;1277.6039427777418;1349.838019437664;1350.7298540291883;12994.15537990037;13164.01269904761;13164.605061848064;13195.2742599157;13245.933770331087;4891.04624598207;5040.697558146523;5160.92258616121;5176.767045482962;5184.455632158644;11112.499937502831;11115.166362522843;11121.443927803739;11128.888711112948;11130.33218006906;5841.094630332305;5846.736896839652;5852.239208902815;5853.268341457318;5857.326407463196;4650.651201159926;4703.576678098988;5119.48805119485;5163.302954101148;5364.51719345253;4324.805383757738;4331.242032644274;4378.408914796525;4420.138073075784;4439.267520859022;1408.2325132802441;1456.6139972907188;1465.9413623454943;1468.6328417895527;1473.1897456398938;1042.6402891002592;1064.80301144287;1074.5433190893839;1081.167501024207;1083.5503266144462;6184.103305020747;6342.59398008006;6402.967985160044;6421.51838612106;6459.09572659828;464.5076219207651;480.61550759392344;482.68143025602933;483.5768702385413;485.7692596016934;4437.378766972626;4468.151051300242;4516.480604730433;4535.115652447741;4598.26427771555;11313.264637798517;11711;11746.530138794353;11773.117016223818;11781.293122412511;10926.071283940795;11276.718082048015;11295.435228238555;11624.825627615422;11631.65105046833;8666;8815.118488151134;8843.734687959372;8882.644694212158;8886.73339799807;6319.431251187415;6621.801345959629;6635.402813746786;6686.1642294713165;6735.225449073419;10697.465126743477;10863.511141998471;10877.238593298393;11005.834912476479;11041.668749937515;9936;10504.842427363748;11125.054370378608;11279.041281491165;11413.942930285295;7876.763697089096;8254.628541715758;8314.916850831414;8320.334373250247;8322.875156872773;9071.727848164566;9090.818183636158;9128.680496182602;9136.63453461854;9137.77155571116;8012.23883731068;8236.464629799044;8299.502029878227;8363.623636936474;8547.059823419295;7448.627568621656;7590.316871481593;7641.617919103911;7692;7726.188750181374;7409.555426674417;7506.174320824595;7509.924900751141;7512.812179695551;7524;5488.862778430412;5521.723913804213;5523.337199536081;5535.972320138373;5705.087186050212;5374.435684253097;5511.889762204813;5585.050541407889;5616.213730077874;5635.577331700057 18:16:11 INFO - PROCESS | 5569 | |4;prototype.html;341.59520967653333;345.65261911778657;360.980146091965;364.0915914792593;370.03236536457155;5545.916811247835;5546;5560.666360018402;5598.63608865424;5624.875024994997;5538.39695343189;5555.611107222486;5556.722163891809;5558.360788509312;5562.7774889004395;3834.118152824852;4117.876463706086;4202.642775364097;4246.97876510617;4289.399484072217;4280.122574872159;4297.80659870305;4298.849540266091;4315.4821421429315;4340.370646256291;4264.33902745075;4281.914361712764;4298.226319262531;4314.094040251535;4314.223439780838;4090.365993271048;4268.189044081614;4285.078708077763;4295.680332620381;4329.523752387235;3778.2254637799183;3784.678302344304;3803.9809800951093;3804.619538046201;3807.8576427072044;5365.892682146375;5370.395358251245;5376.354837419534;5402.513773760341;5423.240746295522;4810.013947140827;4890.975545122287;4891.192953162724;4940.184869496528;4949.430815456197;5689.7724091036725;5753.280839895013;5758.971205143989;5759.971200143972;5769.932562475913;4168.874403910957;4181.870894858371;4186.706930514834;4193.790310484464;4220.978895105535;5846.327672317695;5933.584649074566;5988.413070536355;6045.274567051938;6049.606775559619;4252.383404406344;4260;4265.829366825355;4275.8075886585175;4282.379055037002;4309.84915527955;4322.697411181187;4331.5884990925815;4336.674749393745;4346.608805207483;4865.781039853145;4884.682495637772;4943.307936888838;4985.7343440046825;4994.425641051253;5795.8551036224435;5801.245838041112;5840.94159058404;5846.450690567003;5857.4435428634215;1505;1512.6974605079029;1520.3462511120103;1525.3974680001559;1526.1224795742492;908.9636414543477;961.7980224153006;969.99030009699;970.5195928015678;973.8101070291367;4999.200127979506;5021.096202683479;5031.018951304461;5031.503105341752;5081.8729531762;5250.343707036621;5256.26412302278;5289.233061206103;5289.867753306198;5297.708626025528;5028.491452564216;5035.697858128524;5041.521055499722;5189.5090356629025;5642.463965923231;3156.337169194449;3170.5878235829655;3173.904782856518;3180.6183258009187;3181.1410919051727;6004.609700369462;6134.478569321568;6136.723847426876;6140.385961403913;6166.81499555014;5848.707564621752;5930.495907847795;6045.516358691294;6048.1250812248345;6062.787802426894;6027.397260274025;6103.657195417001;6137.171481849982;6138.14066030756;6150.108234306001;5888.381719919346;5927.695906900475;5928.666050138684;5933.732982015906;5943.435373639497;5658.688772117408;5887.322957859729;5908.438698323653;5946.851328716817;5952.642841429528;6200.162977997912;6209.534284928558;6212.4408803207925;6218.875622487615;6221.96889015552;4126.154138401681;4294.269974104473;4297.09760950204;4297.90403447119;4305.634021108116;5924.763009479572;5966.731497082555;5972.3721562512355;5973.26775235177;5986.88026239464;5816.749398879261;5836.153757705024;5841.415858414124;5844.532437404912;5857.150713146485;5698.487136157766;5821.912671310019;5827.329857066492;5839.532837373085;5857.26784151981;5768.94231057684;5771.509421699203;5786.942130578809;5809.912851307149;5822.359540450462;5705.62913410627;5780.653160810365;5871.590818203686;5888.676122813286;5890.410958904075;5668.206451096932;5701.971490142523;5703.344115426779;5718.971405142947;5719.28508936383 18:16:11 INFO - PROCESS | 5569 | |5;yui.html;830.9044459887115;848.427311564694;880.7137680253917;900.3202582050551;911.5988964855467;17206.569835754097;17696.292148314085;17714.734278985827;17738.733918991227;17740.556486087855;16516.339346426128;16611.750823737653;16667.16664166793;16681.749773753403;16682.49952501424;738.9113306403226;759.9468037237395;762.2529920677739;763.7441457111875;869.0397111192104;895.2524641924001;899.6716198587501;905.6196397513061;906.3066753933247;908.0919080919081;951.5147274889821;952.0717300631864;952.5856252530172;952.6284748947916;952.6808519146094;946.9337146399755;948.6395169835488;951.490952340498;954.1365064616533;954.9188318992894;702.6276073680957;703.5180901082723;704.3625518905399;704.5244459989538;704.6195054670471;14486.348114334774;14566.198859062748;14622.561323160322;14647.560573182822;14662;16362;16872.493825185265;17077.40229091976;17116.400925967533;17949.192286347014;13614.863851361459;13622.727545449035;13666.863331366658;14013.719725605431;14028.85971140296;3925.1757131002237;3971.4241434991764;3994.0613955720673;4016.136530645896;4105.137921036586;89797.55101224453;91347;91411.54294228619;91462;93565;12145.757084858253;12314.692132696755;12364.56724014655;12512.124151309321;12556.372181390894;24653.260402187963;24839.87580062124;24910;24981.375465613506;25000.37499062538;8553.871691924625;8607.096254893271;8961.23829474502;9072.455652660861;9401.529923503798;5359.249705041298;5372.1941708744;5379.35447746273;5493.148561972901;5556.97221513898;3774.2640185163627;3866.4007078902814;3903.6096390361304;3905.3165696003084;3914.4911161548816;637.0343174951266;642.5662677692633;651.8663673946829;651.947844172465;658.1904257762883;682.5631595778706;707.0243064570859;734.1960553194244;747.9663415146332;818.4925346285341;8773.254273386707;9307.255419566418;9614.08666176726;9940.66831449284;10052.748681283027;633.8890694128509;699.8180473077036;733.8935854301096;807.7368520040023;808.7625932323555;4556.84051058218;4603.5396460353695;4824.155772739757;4835.564799168092;4865.318855360252;115081;119829;119966.4001679986;125321;125809;93810;97791;98601;99308;103775;57763.711181443825;79170;79920.60039699764;93501;95376;9424.785175483892;9673.467959262309;9680.515974201402;9713.805723885624;10007.699769006942;57076.429235708776;57198.42801571931;58103;58470;59384.406155939614;70869;83405.58297208718;83446;84735;85958.57020714856;12709.237445753284;12827.10210285271;13287.13633613812;13427.731445371233;13573.864261357656;14966.476173333882;15381.846181538041;16002.359905603646;16015.67968640644;16061.036337819769;12989.545365912149;13441.529546465827;14203.928980355033;14825.851741482447;14968.925155374154;14407.279636017947;14676.853231467549;14680.265986700835;15070.397184112513;15123.773143403077;12670.493180272686;12978.286194259406;13349.199048057148;13365;13598.048136630736;11435.313881166823;12027.15809893299;12117.81823272634;12408.255504669749;12801.487940482277;11469.541218351505;11895.583654572049;12078.396080196131;12395.62813115608;12639.684007899876 18:16:11 INFO - PROCESS | 5569 | __end_tp_report 18:16:11 INFO - PROCESS | 5569 | __start_cc_report 18:16:11 INFO - PROCESS | 5569 | _x_x_mozilla_cycle_collect,1008 18:16:11 INFO - PROCESS | 5569 | __end_cc_report 18:16:11 INFO - PROCESS | 5569 | __startTimestamp1474679771664__endTimestamp 18:16:11 INFO - PROCESS | 5569 | 18:16:11 INFO - PROCESS | 5569 | ------- Summary: start ------- 18:16:11 INFO - PROCESS | 5569 | Number of tests: 6 18:16:11 INFO - PROCESS | 5569 | 18:16:11 INFO - PROCESS | 5569 | [#0] dojo.html Cycles:165 Average:5635.33 Median:5848.01 stddev:648.26 (11.1%) stddev-sans-first:621.06 18:16:11 INFO - PROCESS | 5569 | Values: 3183.8 3183.8 3194.4 3199.0 3240.1 5464.8 5562.9 5575.7 5575.9 5612.4 5676.1 5753.5 5756.0 5760.8 5788.1 5026.4 5150.8 5182.9 5240.7 5259.2 5074.7 5165.5 5209.4 5226.9 5230.1 5084.7 5132.4 5145.2 5156.6 5204.2 5245.3 5255.9 5260.1 5267.2 5296.9 4936.4 5004.4 5055.0 5113.3 5142.4 5489.0 5505.7 5517.5 5527.6 5527.6 6031.2 6039.5 6041.7 6041.7 6050.0 5848.0 6006.8 6014.5 6053.4 6063.2 5654.3 5667.5 5672.7 5673.6 5692.7 6180.7 6188.0 6189.8 6193.0 6199.6 5713.6 5864.6 5928.5 5929.8 6026.3 6069.5 6074.2 6079.0 6101.5 6116.4 6026.7 6031.2 6035.8 6036.5 6052.8 5783.9 5984.6 6028.8 6029.8 6119.1 4626.8 4654.2 4659.0 4659.5 4694.5 4152.0 4164.3 4167.5 4181.8 4190.0 5604.4 5727.3 5799.0 5816.8 5868.3 6169.7 6171.9 6173.3 6176.3 6183.7 5815.0 5819.9 5822.2 5823.4 5828.9 5166.7 5206.9 5230.6 5231.6 5302.5 6276.3 6292.4 6293.6 6295.8 6301.3 6041.0 6050.0 6105.6 6108.1 6109.2 6094.3 6193.8 6202.7 6231.0 6344.5 6003.9 6004.0 6005.1 6006.6 6055.7 6143.9 6160.6 6162.1 6167.1 6168.8 5799.5 5950.0 5979.2 5984.1 6071.6 5961.5 5964.6 5965.0 6004.8 6007.3 5911.7 5955.3 5968.2 5968.6 5970.3 5828.2 5870.4 5931.9 5934.6 5991.3 5824.0 5837.4 5839.0 5841.5 5867.9 18:16:11 INFO - PROCESS | 5569 | 18:16:11 INFO - PROCESS | 5569 | [#1] ext.html Cycles:180 Average:65678.31 Median:39990.70 stddev:135609.35 (339.1%) stddev-sans-first:135901.75 18:16:11 INFO - PROCESS | 5569 | Values: 603.5 635.0 661.5 669.3 675.1 35450.6 38873.2 38887.2 38889.4 38922.4 38833.4 47975.3 48851.3 48978.5 51847.2 9173.1 9304.8 9312.2 9318.7 9330.9 8449.0 8698.5 9231.1 9330.5 9331.9 11237.4 11529.6 11535.1 11839.6 11869.8 11284.2 11339.9 11365.9 11383.4 11386.5 11223.7 11284.7 11287.5 11302.5 11304.5 46123.5 46307.0 46416.0 46496.5 46538.0 3911.2 3922.9 3982.0 4127.4 4606.6 34346.3 34639.7 34706.7 35097.8 36987.8 4590.0 4625.2 4681.0 4703.9 4783.5 371437.0 372300.0 372626.0 372948.1 373421.0 62204.0 62352.4 62378.0 62433.7 62440.7 39877.2 40677.2 40848.4 42410.6 42499.6 17912.6 17945.1 18039.5 18084.3 18098.2 13852.4 13945.7 13946.3 13947.2 13965.6 18473.4 19665.9 20599.3 20694.3 20719.2 7342.7 7397.7 7467.2 7512.3 7683.3 959.4 1088.5 1096.3 1132.5 1154.4 34028.8 34280.1 34364.5 34865.5 35150.5 1126.0 1147.3 1166.2 1166.5 1178.2 17312.7 18147.4 18271.5 18302.7 18418.2 665217.0 669936.0 670779.0 671071.0 674531.0 62863.7 62996.1 63076.7 63107.1 63461.7 1299.6 1405.1 1416.7 1426.9 1444.6 32984.3 34917.3 35237.6 35417.6 35485.6 53796.7 54411.0 54484.0 54494.7 54494.7 416598.0 416765.0 417362.0 418397.0 500781.0 30894.0 31666.2 31666.5 32579.8 32655.8 51241.0 52284.5 52358.0 52941.2 53504.5 43521.0 44514.0 44625.3 45082.3 46166.3 42514.1 42631.0 42919.0 44592.0 44657.1 44133.6 44242.8 44829.6 45446.5 45471.0 35307.6 39453.0 40104.2 40511.2 40520.2 29894.6 30037.4 30337.0 30663.4 30967.0 18:16:11 INFO - PROCESS | 5569 | 18:16:11 INFO - PROCESS | 5569 | [#2] jquery.html Cycles:180 Average:31722.49 Median:24196.65 stddev:91028.89 (376.2%) stddev-sans-first:91253.91 18:16:11 INFO - PROCESS | 5569 | Values: 422.0 429.5 438.1 439.2 446.9 18932.1 19770.5 20704.2 20733.7 20931.9 19273.9 19890.3 20173.5 20512.3 20557.0 5963.4 6193.8 6197.6 6198.7 6201.3 6027.1 6037.7 6038.3 6047.2 6094.8 6055.9 6056.0 6059.6 6063.6 6066.5 5975.4 6031.7 6063.4 6069.8 6075.7 5248.1 5456.4 5470.5 5471.5 5475.6 24194.3 24199.0 24735.5 24943.8 24950.5 2521.8 2532.2 2695.9 2849.8 2864.2 15153.9 15307.5 15315.3 15371.2 15683.5 2065.9 2067.2 2216.5 2278.1 2305.5 65192.7 70347.0 84050.2 86808.6 91799.5 11763.0 11784.1 11990.5 12079.8 12237.7 17276.6 17294.9 17314.7 17319.3 17324.7 8268.1 8358.7 8482.7 8524.5 8586.0 7808.4 7891.9 7923.7 8069.4 8100.7 5083.9 5234.3 5256.3 5289.6 5293.1 2089.5 2096.4 2138.7 2170.4 2178.9 935.0 987.8 1032.8 1089.8 1110.3 17965.2 19249.4 20158.2 20817.9 20909.3 1001.8 1011.7 1048.4 1062.0 1081.5 9257.7 10986.0 11509.5 11625.2 11703.1 540252.3 544280.0 548327.0 565973.0 566270.0 100008.0 101636.5 102501.5 102617.0 105717.5 994.7 999.3 1014.7 1025.7 1026.4 17436.3 17857.2 18214.6 18321.3 18575.7 42944.4 43880.1 44140.1 44589.0 44709.3 57610.4 59335.0 62520.0 63194.4 64041.0 8869.4 9122.2 9238.9 9348.3 9562.2 13797.4 14057.2 14081.8 14401.6 14614.1 11862.2 12113.0 12224.7 12359.3 12435.3 11748.7 12013.9 12274.6 12472.6 12767.0 12083.9 12377.8 12422.3 12530.5 12765.3 9108.7 9195.5 9308.6 9377.6 9669.0 10637.9 10822.7 11603.4 11912.6 12337.7 18:16:11 INFO - PROCESS | 5569 | 18:16:11 INFO - PROCESS | 5569 | [#3] mootools.html Cycles:180 Average:6176.39 Median:4677.11 stddev:3518.61 (75.2%) stddev-sans-first:3510.00 18:16:11 INFO - PROCESS | 5569 | Values: 1378.7 1502.3 1548.5 1699.4 1835.5 8549.3 8896.2 8963.4 9200.8 9229.6 9281.7 9388.3 9392.3 9398.4 9399.3 2472.5 2487.1 2496.5 2496.8 2497.4 2434.9 2521.1 2522.6 2524.5 2526.8 2384.3 2427.9 2499.3 2499.8 2500.5 2466.1 2492.8 2493.9 2499.0 2499.0 2297.8 2345.7 2350.2 2351.8 2459.3 9070.0 9408.0 9457.7 9503.8 9513.0 5231.6 5374.8 5438.0 5447.8 5452.2 3494.5 3519.7 3537.2 3555.1 3598.1 1244.6 1270.1 1277.6 1349.8 1350.7 12994.2 13164.0 13164.6 13195.3 13245.9 4891.0 5040.7 5160.9 5176.8 5184.5 11112.5 11115.2 11121.4 11128.9 11130.3 5841.1 5846.7 5852.2 5853.3 5857.3 4650.7 4703.6 5119.5 5163.3 5364.5 4324.8 4331.2 4378.4 4420.1 4439.3 1408.2 1456.6 1465.9 1468.6 1473.2 1042.6 1064.8 1074.5 1081.2 1083.6 6184.1 6342.6 6403.0 6421.5 6459.1 464.5 480.6 482.7 483.6 485.8 4437.4 4468.2 4516.5 4535.1 4598.3 11313.3 11711.0 11746.5 11773.1 11781.3 10926.1 11276.7 11295.4 11624.8 11631.7 8666.0 8815.1 8843.7 8882.6 8886.7 6319.4 6621.8 6635.4 6686.2 6735.2 10697.5 10863.5 10877.2 11005.8 11041.7 9936.0 10504.8 11125.1 11279.0 11413.9 7876.8 8254.6 8314.9 8320.3 8322.9 9071.7 9090.8 9128.7 9136.6 9137.8 8012.2 8236.5 8299.5 8363.6 8547.1 7448.6 7590.3 7641.6 7692.0 7726.2 7409.6 7506.2 7509.9 7512.8 7524.0 5488.9 5521.7 5523.3 5536.0 5705.1 5374.4 5511.9 5585.1 5616.2 5635.6 18:16:11 INFO - PROCESS | 5569 | 18:16:11 INFO - PROCESS | 5569 | [#4] prototype.html Cycles:180 Average:4844.30 Median:5545.96 stddev:1421.07 (25.6%) stddev-sans-first:1384.29 18:16:11 INFO - PROCESS | 5569 | Values: 341.6 345.7 361.0 364.1 370.0 5545.9 5546.0 5560.7 5598.6 5624.9 5538.4 5555.6 5556.7 5558.4 5562.8 3834.1 4117.9 4202.6 4247.0 4289.4 4280.1 4297.8 4298.8 4315.5 4340.4 4264.3 4281.9 4298.2 4314.1 4314.2 4090.4 4268.2 4285.1 4295.7 4329.5 3778.2 3784.7 3804.0 3804.6 3807.9 5365.9 5370.4 5376.4 5402.5 5423.2 4810.0 4891.0 4891.2 4940.2 4949.4 5689.8 5753.3 5759.0 5760.0 5769.9 4168.9 4181.9 4186.7 4193.8 4221.0 5846.3 5933.6 5988.4 6045.3 6049.6 4252.4 4260.0 4265.8 4275.8 4282.4 4309.8 4322.7 4331.6 4336.7 4346.6 4865.8 4884.7 4943.3 4985.7 4994.4 5795.9 5801.2 5840.9 5846.5 5857.4 1505.0 1512.7 1520.3 1525.4 1526.1 909.0 961.8 970.0 970.5 973.8 4999.2 5021.1 5031.0 5031.5 5081.9 5250.3 5256.3 5289.2 5289.9 5297.7 5028.5 5035.7 5041.5 5189.5 5642.5 3156.3 3170.6 3173.9 3180.6 3181.1 6004.6 6134.5 6136.7 6140.4 6166.8 5848.7 5930.5 6045.5 6048.1 6062.8 6027.4 6103.7 6137.2 6138.1 6150.1 5888.4 5927.7 5928.7 5933.7 5943.4 5658.7 5887.3 5908.4 5946.9 5952.6 6200.2 6209.5 6212.4 6218.9 6222.0 4126.2 4294.3 4297.1 4297.9 4305.6 5924.8 5966.7 5972.4 5973.3 5986.9 5816.7 5836.2 5841.4 5844.5 5857.2 5698.5 5821.9 5827.3 5839.5 5857.3 5768.9 5771.5 5786.9 5809.9 5822.4 5705.6 5780.7 5871.6 5888.7 5890.4 5668.2 5702.0 5703.3 5719.0 5719.3 18:16:11 INFO - PROCESS | 5569 | 18:16:11 INFO - PROCESS | 5569 | [#5] yui.html Cycles:180 Average:22163.38 Median:4829.86 stddev:31576.32 (653.8%) stddev-sans-first:31624.27 18:16:11 INFO - PROCESS | 5569 | Values: 830.9 848.4 880.7 900.3 911.6 17206.6 17696.3 17714.7 17738.7 17740.6 16516.3 16611.8 16667.2 16681.7 16682.5 738.9 759.9 762.3 763.7 869.0 895.3 899.7 905.6 906.3 908.1 951.5 952.1 952.6 952.6 952.7 946.9 948.6 951.5 954.1 954.9 702.6 703.5 704.4 704.5 704.6 14486.3 14566.2 14622.6 14647.6 14662.0 16362.0 16872.5 17077.4 17116.4 17949.2 13614.9 13622.7 13666.9 14013.7 14028.9 3925.2 3971.4 3994.1 4016.1 4105.1 89797.6 91347.0 91411.5 91462.0 93565.0 12145.8 12314.7 12364.6 12512.1 12556.4 24653.3 24839.9 24910.0 24981.4 25000.4 8553.9 8607.1 8961.2 9072.5 9401.5 5359.2 5372.2 5379.4 5493.1 5557.0 3774.3 3866.4 3903.6 3905.3 3914.5 637.0 642.6 651.9 651.9 658.2 682.6 707.0 734.2 748.0 818.5 8773.3 9307.3 9614.1 9940.7 10052.7 633.9 699.8 733.9 807.7 808.8 4556.8 4603.5 4824.2 4835.6 4865.3 115081.0 119829.0 119966.4 125321.0 125809.0 93810.0 97791.0 98601.0 99308.0 103775.0 57763.7 79170.0 79920.6 93501.0 95376.0 9424.8 9673.5 9680.5 9713.8 10007.7 57076.4 57198.4 58103.0 58470.0 59384.4 70869.0 83405.6 83446.0 84735.0 85958.6 12709.2 12827.1 13287.1 13427.7 13573.9 14966.5 15381.8 16002.4 16015.7 16061.0 12989.5 13441.5 14203.9 14825.9 14968.9 14407.3 14676.9 14680.3 15070.4 15123.8 12670.5 12978.3 13349.2 13365.0 13598.0 11435.3 12027.2 12117.8 12408.3 12801.5 11469.5 11895.6 12078.4 12395.6 12639.7 18:16:11 INFO - PROCESS | 5569 | -------- Summary: end -------- 18:16:11 INFO - PROCESS | 5569 | 18:16:12 INFO - TEST-INFO | 5569: exit 0 18:16:12 INFO - TEST-OK | dromaeo_css | took 1113172ms 18:16:12 INFO - TEST-START | kraken 18:16:12 INFO - operating with platform_type : linux_ 18:16:12 INFO - Initialising browser for kraken test... 18:16:12 INFO - TEST-INFO | started process 5760 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2BvuF7/profile http://localhost:42734/getInfo.html) 18:16:16 INFO - PROCESS | 5760 | __metrics Screen width/height:1600/1200 18:16:16 INFO - PROCESS | 5760 | colorDepth:24 18:16:16 INFO - PROCESS | 5760 | Browser inner width/height: 1024/697 18:16:16 INFO - PROCESS | 5760 | __metrics 18:16:17 INFO - PROCESS | 5760 | 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" 18:16:17 INFO - PROCESS | 5760 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:16:17 INFO - PROCESS | 5760 | console.error: PushService: 18:16:17 INFO - PROCESS | 5760 | stateChangeProcessEnqueue: Error transitioning state 18:16:17 INFO - PROCESS | 5760 | UnknownError 18:16:18 INFO - TEST-INFO | 5760: exit 0 18:16:18 INFO - Browser initialized. 18:16:18 INFO - Running cycle 1/1 for kraken test... 18:16:18 INFO - TEST-INFO | started process 5847 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2BvuF7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:16:29 INFO - PROCESS | 5847 | RSS: Main: 143282176 18:16:29 INFO - PROCESS | 5847 | 18:16:32 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42734/tests/kraken/driver.html?testName=audio-beat-detection) 18:16:33 INFO - PROCESS | 5847 | RSS: Main: 198975488 18:16:33 INFO - PROCESS | 5847 | 18:16:35 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42734/tests/kraken/driver.html?testName=audio-dft) 18:16:36 INFO - PROCESS | 5847 | RSS: Main: 182730752 18:16:36 INFO - PROCESS | 5847 | 18:16:39 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42734/tests/kraken/driver.html?testName=audio-fft) 18:16:39 INFO - PROCESS | 5847 | RSS: Main: 273960960 18:16:39 INFO - PROCESS | 5847 | 18:16:41 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42734/tests/kraken/driver.html?testName=audio-oscillator) 18:16:42 INFO - PROCESS | 5847 | RSS: Main: 179748864 18:16:42 INFO - PROCESS | 5847 | 18:16:44 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42734/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:16:45 INFO - PROCESS | 5847 | RSS: Main: 178089984 18:16:45 INFO - PROCESS | 5847 | 18:16:48 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42734/tests/kraken/driver.html?testName=imaging-darkroom) 18:16:48 INFO - PROCESS | 5847 | RSS: Main: 214478848 18:16:48 INFO - PROCESS | 5847 | 18:16:52 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42734/tests/kraken/driver.html?testName=imaging-desaturate) 18:16:52 INFO - PROCESS | 5847 | RSS: Main: 213745664 18:16:52 INFO - PROCESS | 5847 | 18:16:55 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42734/tests/kraken/driver.html?testName=json-parse-financial) 18:16:55 INFO - PROCESS | 5847 | RSS: Main: 213626880 18:16:55 INFO - PROCESS | 5847 | 18:16:57 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42734/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:16:58 INFO - PROCESS | 5847 | RSS: Main: 175751168 18:16:58 INFO - PROCESS | 5847 | 18:16:59 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42734/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:17:00 INFO - PROCESS | 5847 | RSS: Main: 183365632 18:17:00 INFO - PROCESS | 5847 | 18:17:02 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42734/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:17:03 INFO - PROCESS | 5847 | RSS: Main: 184668160 18:17:03 INFO - PROCESS | 5847 | 18:17:05 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42734/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:17:06 INFO - PROCESS | 5847 | RSS: Main: 186314752 18:17:06 INFO - PROCESS | 5847 | 18:17:08 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42734/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:17:09 INFO - PROCESS | 5847 | RSS: Main: 182665216 18:17:09 INFO - PROCESS | 5847 | 18:17:10 INFO - PROCESS | 5847 | Cycle 1(1): loaded http://localhost:42734/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42734/tests/kraken/driver.html?testName=ai-astar) 18:17:11 INFO - PROCESS | 5847 | RSS: Main: 234553344 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | __start_tp_report 18:17:11 INFO - PROCESS | 5847 | _x_x_mozilla_page_load 18:17:11 INFO - PROCESS | 5847 | _x_x_mozilla_page_load_details 18:17:11 INFO - PROCESS | 5847 | |i|pagename|runs| 18:17:11 INFO - PROCESS | 5847 | |0;ai-astar;111;112;120;110;113;111;110;111;111;110 18:17:11 INFO - PROCESS | 5847 | |1;audio-beat-detection;141;117;140;181;115;149;143;117;170;114 18:17:11 INFO - PROCESS | 5847 | |2;audio-dft;163;160;161;161;161;161;160;159;161;162 18:17:11 INFO - PROCESS | 5847 | |3;audio-fft;74;73;74;185;76;76;75;75;169;75 18:17:11 INFO - PROCESS | 5847 | |4;audio-oscillator;83;82;143;82;83;83;150;83;84;150 18:17:11 INFO - PROCESS | 5847 | |5;imaging-gaussian-blur;117;116;117;116;116;117;116;116;116;117 18:17:11 INFO - PROCESS | 5847 | |6;imaging-darkroom;146;146;145;146;146;146;145;145;145;147 18:17:11 INFO - PROCESS | 5847 | |7;imaging-desaturate;95;95;96;95;94;95;95;95;95;95 18:17:11 INFO - PROCESS | 5847 | |8;json-parse-financial;51;51;50;49;51;52;50;51;52;51 18:17:11 INFO - PROCESS | 5847 | |9;json-stringify-tinderbox;59;59;60;60;60;59;59;60;59;60 18:17:11 INFO - PROCESS | 5847 | |10;stanford-crypto-aes;74;74;74;83;72;73;73;82;73;72 18:17:11 INFO - PROCESS | 5847 | |11;stanford-crypto-ccm;120;117;116;125;126;116;114;123;159;128 18:17:11 INFO - PROCESS | 5847 | |12;stanford-crypto-pbkdf2;154;153;154;151;148;151;150;150;154;152 18:17:11 INFO - PROCESS | 5847 | |13;stanford-crypto-sha256-iterative;63;60;63;61;60;60;68;63;60;61 18:17:11 INFO - PROCESS | 5847 | __end_tp_report 18:17:11 INFO - PROCESS | 5847 | __start_cc_report 18:17:11 INFO - PROCESS | 5847 | _x_x_mozilla_cycle_collect,1634 18:17:11 INFO - PROCESS | 5847 | __end_cc_report 18:17:11 INFO - PROCESS | 5847 | __startTimestamp1474679831177__endTimestamp 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | ------- Summary: start ------- 18:17:11 INFO - PROCESS | 5847 | Number of tests: 14 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#0] ai-astar Cycles:10 Average:111.90 Median:111.00 stddev:3.00 (2.7%) stddev-sans-first:3.16 18:17:11 INFO - PROCESS | 5847 | Values: 111.0 112.0 120.0 110.0 113.0 111.0 110.0 111.0 111.0 110.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#1] audio-beat-detection Cycles:10 Average:138.70 Median:142.00 stddev:23.60 (16.6%) stddev-sans-first:25.02 18:17:11 INFO - PROCESS | 5847 | Values: 141.0 117.0 140.0 181.0 115.0 149.0 143.0 117.0 170.0 114.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.10 (0.7%) stddev-sans-first:0.87 18:17:11 INFO - PROCESS | 5847 | Values: 163.0 160.0 161.0 161.0 161.0 161.0 160.0 159.0 161.0 162.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#3] audio-fft Cycles:10 Average:95.20 Median:75.00 stddev:43.29 (57.7%) stddev-sans-first:45.23 18:17:11 INFO - PROCESS | 5847 | Values: 74.0 73.0 74.0 185.0 76.0 76.0 75.0 75.0 169.0 75.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#4] audio-oscillator Cycles:10 Average:102.30 Median:83.00 stddev:31.37 (37.8%) stddev-sans-first:32.49 18:17:11 INFO - PROCESS | 5847 | Values: 83.0 82.0 143.0 82.0 83.0 83.0 150.0 83.0 84.0 150.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 18:17:11 INFO - PROCESS | 5847 | Values: 117.0 116.0 117.0 116.0 116.0 117.0 116.0 116.0 116.0 117.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 18:17:11 INFO - PROCESS | 5847 | Values: 146.0 146.0 145.0 146.0 146.0 146.0 145.0 145.0 145.0 147.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.47 (0.5%) stddev-sans-first:0.50 18:17:11 INFO - PROCESS | 5847 | Values: 95.0 95.0 96.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:0.92 (1.8%) stddev-sans-first:0.97 18:17:11 INFO - PROCESS | 5847 | Values: 51.0 51.0 50.0 49.0 51.0 52.0 50.0 51.0 52.0 51.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 18:17:11 INFO - PROCESS | 5847 | Values: 59.0 59.0 60.0 60.0 60.0 59.0 59.0 60.0 59.0 60.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:74.00 stddev:4.03 (5.4%) stddev-sans-first:4.26 18:17:11 INFO - PROCESS | 5847 | Values: 74.0 74.0 74.0 83.0 72.0 73.0 73.0 82.0 73.0 72.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#11] stanford-crypto-ccm Cycles:10 Average:124.40 Median:124.00 stddev:13.07 (10.5%) stddev-sans-first:13.77 18:17:11 INFO - PROCESS | 5847 | Values: 120.0 117.0 116.0 125.0 126.0 116.0 114.0 123.0 159.0 128.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.70 Median:152.50 stddev:2.06 (1.3%) stddev-sans-first:2.01 18:17:11 INFO - PROCESS | 5847 | Values: 154.0 153.0 154.0 151.0 148.0 151.0 150.0 150.0 154.0 152.0 18:17:11 INFO - PROCESS | 5847 | 18:17:11 INFO - PROCESS | 5847 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:2.51 (4.1%) stddev-sans-first:2.64 18:17:11 INFO - PROCESS | 5847 | Values: 63.0 60.0 63.0 61.0 60.0 60.0 68.0 63.0 60.0 61.0 18:17:11 INFO - PROCESS | 5847 | -------- Summary: end -------- 18:17:11 INFO - PROCESS | 5847 | 18:17:12 INFO - TEST-INFO | 5847: exit 0 18:17:12 INFO - TEST-OK | kraken | took 59951ms 18:17:12 INFO - SUITE-END | took 1173s 18:17:12 INFO - Completed test suite (00:19:33) 18:17:12 INFO - javascript benchmark 18:17:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3183.7771356005073, 3183.7930534515253, 3194.440972829754, 3198.968010319896, 3240.1089700332404, 5464.808731694391, 5562.86092847679, 5575.665460072399, 5575.888482230358, 5612.354579223385, 5676.120201368784, 5753.482186603205, 5756.0, 5760.827175184741, 5788.073908174682, 5026.447090820006, 5150.819721309755, 5182.8704282393055, 5240.68555886648, 5259.237410575464, 5074.746262686869, 5165.483451654842, 5209.426963034063, 5226.92159617606, 5230.084735171349, 5084.720340381277, 5132.384113906336, 5145.151050076733, 5156.561692256144, 5204.219367094928, 5245.291885595453, 5255.947440525584, 5260.132078207092, 5267.236250743659, 5296.867578310535, 4936.4076310842565, 5004.374453193351, 5054.974725126351, 5113.309703190059, 5142.408623008365, 5489.039418101816, 5505.724713764296, 5517.503424691797, 5527.585431092644, 5527.640703354309, 6031.24609423822, 6039.486643635296, 6041.667708276043, 6041.667708276043, 6050.0, 5848.005839007336, 6006.8498287542725, 6014.488768454687, 6053.424924632153, 6063.211782468251, 5654.349749778746, 5667.489925906606, 5672.688002159838, 5673.631213971079, 5692.686902220376, 6180.721867515973, 6187.969060154671, 6189.84525386869, 6192.97815860378, 6199.628022318675, 5713.600047996601, 5864.560157988168, 5928.466438020599, 5929.762809487658, 6026.306974697878, 6069.51443884497, 6074.240719910012, 6078.97901778173, 6101.481374083164, 6116.388361163937, 6026.728797204137, 6031.2159419276095, 6035.818925432244, 6036.547258955596, 6052.788152414644, 5783.913241301384, 5984.611000284967, 6028.758849646053, 6029.8191054268445, 6119.143319935123, 4626.768661566908, 4654.232051711429, 4659.044895796354, 4659.510751371125, 4694.507076756891, 4152.045029643199, 4164.312888373384, 4167.458230430025, 4181.832726690959, 4189.958100419018, 5604.439556044363, 5727.255456790591, 5799.0, 5816.796412125555, 5868.2664666916635, 6169.722362493699, 6171.907421388682, 6173.290071641729, 6176.320604733476, 6183.721732522047, 5814.9709251454315, 5819.854503637358, 5822.184894114827, 5823.417658234227, 5828.912566311508, 5166.741662916839, 5206.869828254248, 5230.555402790806, 5231.607629427732, 5302.496262855023, 6276.340984196685, 6292.433680968643, 6293.590916590407, 6295.842603934847, 6301.275353334425, 6041.0, 6050.031994880798, 6105.633661980295, 6108.083787431743, 6109.236345456818, 6094.299155597163, 6193.81418557444, 6202.689865506797, 6231.034189700604, 6344.492440604648, 6003.87992240144, 6004.039353703386, 6005.099235114768, 6006.579539432198, 6055.666938318434, 6143.877122457615, 6160.568760186744, 6162.137300777805, 6167.105769663463, 6168.814935551941, 5799.478046975623, 5950.047992321381, 5979.1928089707335, 5984.072468767348, 6071.635701857903, 5961.493273071837, 5964.612300200473, 5965.0, 6004.759809607567, 6007.279126504847, 5911.733971971357, 5955.344912059583, 5968.194293770285, 5968.612040217372, 5970.313413957277, 5828.184054232326, 5870.442307980735, 5931.881362372815, 5934.6142500737315, 5991.2510936132985, 5823.970880145572, 5837.387074357302, 5839.0, 5841.50347220491, 5867.911981320369], "value": 5590.459132651133, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.4990957505271, 634.9619022858628, 661.4774328280658, 669.2738378858937, 675.1122274209414, 35450.64549354509, 38873.22253554927, 38887.22225555487, 38889.41665875014, 38922.41616375757, 38833.417498737545, 47975.28037079447, 48851.267230991565, 48978.51021489784, 51847.222291665654, 9173.128552787475, 9304.767380815467, 9312.208462280714, 9318.72043838686, 9330.860037099448, 8448.957755211215, 8698.521581313025, 9231.076892310748, 9330.486823224719, 9331.860022099674, 11237.381943993078, 11529.596464123757, 11535.134864885125, 11839.64481065565, 11869.7626047479, 11284.153688473356, 11339.943300283447, 11365.943170284138, 11383.373914434702, 11386.54453821846, 11223.66329010131, 11284.717882052932, 11287.548498060069, 11302.547898084067, 11304.547818087267, 46123.53876461226, 46307.0, 46416.0, 46496.53503464956, 46538.0, 3911.198204368098, 3922.941155882664, 3981.980090099531, 4127.422160897477, 4606.56237657422, 34346.31307373864, 34639.65360346389, 34706.65293347085, 35097.82451087728, 36987.81506092479, 4589.954100458986, 4625.236835922069, 4680.953190468086, 4703.882402939919, 4783.4977327380475, 371437.0, 372300.0, 372626.0, 372948.1352593247, 373421.0, 62204.0, 62352.376476235564, 62378.0, 62433.68783156055, 62440.68779656073, 39877.20245595072, 40677.18645627071, 40848.387274190914, 42410.57589424066, 42499.575004249564, 17912.641747164984, 17945.102744862965, 18039.549011274823, 18084.276628934695, 18098.185581648857, 13852.376643051088, 13945.721085578232, 13946.302684865715, 13947.16317020982, 13965.58103256904, 18473.44579662612, 19665.901670491556, 20599.279025234046, 20694.275700350416, 20719.171233150806, 7342.743003994941, 7397.741079062207, 7467.215942326086, 7512.286332798375, 7683.346915512244, 959.4435227567993, 1088.4992903264429, 1096.331237944853, 1132.456420917964, 1154.3997121496775, 34028.82985585056, 34280.14299642529, 34364.48453273203, 34865.47701784475, 35150.472742908874, 1125.9605913792977, 1147.2829481574017, 1166.2302880099094, 1166.4750862112085, 1178.2164860367932, 17312.740308895376, 18147.364842230458, 18271.543211419554, 18302.725459118123, 18418.17118229683, 665217.0, 669936.0, 670779.0, 671071.0, 674531.0187592539, 62863.685681571296, 62996.05505917415, 63076.684616576626, 63107.05339419912, 63461.68269158625, 1299.636101891472, 1405.1358414575168, 1416.7249475471951, 1426.9143851369158, 1444.6460617148866, 32984.34031319312, 34917.30165396729, 35237.64762352344, 35417.64582354143, 35485.64514354823, 53796.73101634467, 54411.0, 54484.0, 54494.72752636211, 54494.7275263637, 416598.0, 416765.0, 417362.0, 418397.0, 500781.0, 30894.0, 31666.208344791565, 31666.525002125447, 32579.837100814344, 32655.836720816245, 51241.0, 52284.47715522796, 52358.0, 52941.20588191103, 53504.46495534995, 43521.0, 44514.0, 44625.33062004137, 45082.3237651442, 46166.30750538812, 42514.149717006105, 42631.0, 42919.0, 44592.0, 44657.09728867613, 44133.55866441423, 44242.77878610586, 44829.55170448254, 45446.54553454423, 45471.0, 35307.64692353044, 39453.0, 40104.197916042096, 40511.1897762049, 40520.189596208504, 29894.551581725857, 30037.446817019925, 30337.0, 30663.386732265677, 30967.0], "value": 21598.88817046642, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.96624270058396, 429.5230112241405, 438.0909612553951, 439.15462734236604, 446.8748750349901, 18932.148053337598, 19770.505737356554, 20704.171833126675, 20733.68899466509, 20931.895340523315, 19273.91617341222, 19890.303839365624, 20173.495662608442, 20512.28207012755, 20557.0, 5963.373845746189, 6193.7832175873855, 6197.628142311476, 6198.690065496708, 6201.255849298091, 6027.095935609672, 6037.728302226388, 6038.33578306386, 6047.244094488189, 6094.756209751605, 6055.939440605582, 6056.031035034395, 6059.606125601844, 6063.6361818290825, 6066.484348830355, 5975.402459754033, 6031.6984150792505, 6063.363346848561, 6069.78755743549, 6075.665838378887, 5248.081585722483, 5456.427075157091, 5470.480304371078, 5471.480209380103, 5475.589330800206, 24194.274171774876, 24199.03203871843, 24735.50528989428, 24943.750562494504, 24950.50098998028, 2521.773040426352, 2532.202356257773, 2695.8517281549507, 2849.786266030056, 2864.240976141324, 15153.924230378887, 15307.46423875159, 15315.31081101353, 15371.231438428034, 15683.451079212287, 2065.9380218593465, 2067.157806946126, 2216.468047668564, 2278.1343089626002, 2305.5043165719285, 65192.674036629985, 70347.0, 84050.15949840484, 86808.56595717045, 91799.54100229456, 11763.0, 11784.116191285688, 11990.460429280529, 12079.818802717966, 12237.694057648452, 17276.568085797957, 17294.913525432294, 17314.65370692579, 17319.307227711004, 17324.653506930044, 8268.09051004389, 8358.707445239388, 8482.745517634481, 8524.488530688179, 8586.0, 7808.414368922354, 7891.921080789119, 7923.7226697065325, 8069.394795390252, 8100.675972961016, 5083.923741143886, 5234.345706786652, 5256.342957130359, 5289.629725919149, 5293.126634105406, 2089.5194105355854, 2096.4234835420075, 2138.700581918533, 2170.3814412892248, 2178.945526361854, 934.9579268932914, 987.7579992901785, 1032.7521394865178, 1089.8201796703613, 1110.3393480878865, 17965.191566379544, 19249.422517324503, 20158.193672253237, 20817.888317589128, 20909.268175614085, 1001.84972254161, 1011.7420057885192, 1048.4024106259512, 1061.9681409557725, 1081.4862940103387, 9257.703921451002, 10986.0, 11509.48207330672, 11625.24435911663, 11703.063754899758, 540252.2565485863, 544280.0, 548327.0, 565973.0, 566270.0, 100008.0, 101636.49181754192, 102501.48749256205, 102617.0, 105717.47141264398, 994.6667866264737, 999.3204620857887, 1014.7463134216594, 1025.707673313117, 1026.4457193115634, 17436.302547897943, 17857.196426160594, 18214.635707286045, 18321.267149313877, 18575.72136417982, 42944.35583466313, 43880.122397552506, 44140.11719765651, 44589.0, 44709.329360058975, 57610.423895762186, 59335.0, 62520.0, 63194.368056318846, 64041.0, 8869.379143460154, 9122.224610908148, 9238.907610923805, 9348.252139828934, 9562.235021198148, 13797.448102075805, 14057.226852522801, 14081.834433580567, 14401.63995900111, 14614.123152610877, 11862.169648124549, 12113.030957523553, 12224.694382640506, 12359.258444493, 12435.253884766942, 11748.706282342668, 12013.879861201276, 12274.570390036306, 12472.625821225378, 12767.042471814466, 12083.879161208275, 12377.814332784836, 12422.316772577233, 12530.498780048696, 12765.297908615115, 9108.681196158102, 9195.540222988957, 9308.581113850028, 9377.624895004123, 9668.984756600737, 10637.9468102659, 10822.710437459737, 11603.36181510025, 11912.583059592873, 12337.691557711129], "value": 9867.729714576824, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1378.6966867289204, 1502.2939218567276, 1548.4735190035385, 1699.3797263998636, 1835.45853973078, 8549.2733117685, 8896.199342059213, 8963.37256392052, 9200.815983680322, 9229.630814767417, 9281.675141370053, 9388.342816002883, 9392.342536022481, 9398.436093834374, 9399.295052871028, 2472.5425796227664, 2487.092211342856, 2496.5131799299143, 2496.8127390445698, 2497.4131079196372, 2434.926952191437, 2521.1428114441087, 2522.608995605675, 2524.469861329114, 2526.7852232560163, 2384.2966324934187, 2427.8543287402813, 2499.250224932522, 2499.787518060958, 2500.4874000829786, 2466.05057053035, 2492.7756501914823, 2493.8628375439343, 2498.95002099957, 2498.9754200777684, 2297.7702229776887, 2345.6598793175067, 2350.200931683236, 2351.7648235176516, 2459.348272707734, 9070.047644997312, 9407.952960235223, 9457.716268511957, 9503.809923801486, 9513.048695130501, 5231.633785635007, 5374.838754837361, 5437.972810135963, 5447.836564903059, 5452.209429632681, 3494.493298471733, 3519.683228509421, 3537.1510837398837, 3555.075680323109, 3598.064503229179, 1244.564402459141, 1270.1109223543465, 1277.6039427777418, 1349.838019437664, 1350.7298540291883, 12994.15537990037, 13164.01269904761, 13164.605061848064, 13195.2742599157, 13245.933770331087, 4891.04624598207, 5040.697558146523, 5160.92258616121, 5176.767045482962, 5184.455632158644, 11112.499937502831, 11115.166362522843, 11121.443927803739, 11128.888711112948, 11130.33218006906, 5841.094630332305, 5846.736896839652, 5852.239208902815, 5853.268341457318, 5857.326407463196, 4650.651201159926, 4703.576678098988, 5119.48805119485, 5163.302954101148, 5364.51719345253, 4324.805383757738, 4331.242032644274, 4378.408914796525, 4420.138073075784, 4439.267520859022, 1408.2325132802441, 1456.6139972907188, 1465.9413623454943, 1468.6328417895527, 1473.1897456398938, 1042.6402891002592, 1064.80301144287, 1074.5433190893839, 1081.167501024207, 1083.5503266144462, 6184.103305020747, 6342.59398008006, 6402.967985160044, 6421.51838612106, 6459.09572659828, 464.5076219207651, 480.61550759392344, 482.68143025602933, 483.5768702385413, 485.7692596016934, 4437.378766972626, 4468.151051300242, 4516.480604730433, 4535.115652447741, 4598.26427771555, 11313.264637798517, 11711.0, 11746.530138794353, 11773.117016223818, 11781.293122412511, 10926.071283940795, 11276.718082048015, 11295.435228238555, 11624.825627615422, 11631.65105046833, 8666.0, 8815.118488151134, 8843.734687959372, 8882.644694212158, 8886.73339799807, 6319.431251187415, 6621.801345959629, 6635.402813746786, 6686.1642294713165, 6735.225449073419, 10697.465126743477, 10863.511141998471, 10877.238593298393, 11005.834912476479, 11041.668749937515, 9936.0, 10504.842427363748, 11125.054370378608, 11279.041281491165, 11413.942930285295, 7876.763697089096, 8254.628541715758, 8314.916850831414, 8320.334373250247, 8322.875156872773, 9071.727848164566, 9090.818183636158, 9128.680496182602, 9136.63453461854, 9137.77155571116, 8012.23883731068, 8236.464629799044, 8299.502029878227, 8363.623636936474, 8547.059823419295, 7448.627568621656, 7590.316871481593, 7641.617919103911, 7692.0, 7726.188750181374, 7409.555426674417, 7506.174320824595, 7509.924900751141, 7512.812179695551, 7524.0, 5488.862778430412, 5521.723913804213, 5523.337199536081, 5535.972320138373, 5705.087186050212, 5374.435684253097, 5511.889762204813, 5585.050541407889, 5616.213730077874, 5635.577331700057], "value": 4872.358201452897, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.59520967653333, 345.65261911778657, 360.980146091965, 364.0915914792593, 370.03236536457155, 5545.916811247835, 5546.0, 5560.666360018402, 5598.63608865424, 5624.875024994997, 5538.39695343189, 5555.611107222486, 5556.722163891809, 5558.360788509312, 5562.7774889004395, 3834.118152824852, 4117.876463706086, 4202.642775364097, 4246.97876510617, 4289.399484072217, 4280.122574872159, 4297.80659870305, 4298.849540266091, 4315.4821421429315, 4340.370646256291, 4264.33902745075, 4281.914361712764, 4298.226319262531, 4314.094040251535, 4314.223439780838, 4090.365993271048, 4268.189044081614, 4285.078708077763, 4295.680332620381, 4329.523752387235, 3778.2254637799183, 3784.678302344304, 3803.9809800951093, 3804.619538046201, 3807.8576427072044, 5365.892682146375, 5370.395358251245, 5376.354837419534, 5402.513773760341, 5423.240746295522, 4810.013947140827, 4890.975545122287, 4891.192953162724, 4940.184869496528, 4949.430815456197, 5689.7724091036725, 5753.280839895013, 5758.971205143989, 5759.971200143972, 5769.932562475913, 4168.874403910957, 4181.870894858371, 4186.706930514834, 4193.790310484464, 4220.978895105535, 5846.327672317695, 5933.584649074566, 5988.413070536355, 6045.274567051938, 6049.606775559619, 4252.383404406344, 4260.0, 4265.829366825355, 4275.8075886585175, 4282.379055037002, 4309.84915527955, 4322.697411181187, 4331.5884990925815, 4336.674749393745, 4346.608805207483, 4865.781039853145, 4884.682495637772, 4943.307936888838, 4985.7343440046825, 4994.425641051253, 5795.8551036224435, 5801.245838041112, 5840.94159058404, 5846.450690567003, 5857.4435428634215, 1505.0, 1512.6974605079029, 1520.3462511120103, 1525.3974680001559, 1526.1224795742492, 908.9636414543477, 961.7980224153006, 969.99030009699, 970.5195928015678, 973.8101070291367, 4999.200127979506, 5021.096202683479, 5031.018951304461, 5031.503105341752, 5081.8729531762, 5250.343707036621, 5256.26412302278, 5289.233061206103, 5289.867753306198, 5297.708626025528, 5028.491452564216, 5035.697858128524, 5041.521055499722, 5189.5090356629025, 5642.463965923231, 3156.337169194449, 3170.5878235829655, 3173.904782856518, 3180.6183258009187, 3181.1410919051727, 6004.609700369462, 6134.478569321568, 6136.723847426876, 6140.385961403913, 6166.81499555014, 5848.707564621752, 5930.495907847795, 6045.516358691294, 6048.1250812248345, 6062.787802426894, 6027.397260274025, 6103.657195417001, 6137.171481849982, 6138.14066030756, 6150.108234306001, 5888.381719919346, 5927.695906900475, 5928.666050138684, 5933.732982015906, 5943.435373639497, 5658.688772117408, 5887.322957859729, 5908.438698323653, 5946.851328716817, 5952.642841429528, 6200.162977997912, 6209.534284928558, 6212.4408803207925, 6218.875622487615, 6221.96889015552, 4126.154138401681, 4294.269974104473, 4297.09760950204, 4297.90403447119, 4305.634021108116, 5924.763009479572, 5966.731497082555, 5972.3721562512355, 5973.26775235177, 5986.88026239464, 5816.749398879261, 5836.153757705024, 5841.415858414124, 5844.532437404912, 5857.150713146485, 5698.487136157766, 5821.912671310019, 5827.329857066492, 5839.532837373085, 5857.26784151981, 5768.94231057684, 5771.509421699203, 5786.942130578809, 5809.912851307149, 5822.359540450462, 5705.62913410627, 5780.653160810365, 5871.590818203686, 5888.676122813286, 5890.410958904075, 5668.206451096932, 5701.971490142523, 5703.344115426779, 5718.971405142947, 5719.28508936383], "value": 4395.422398428457, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.9044459887115, 848.427311564694, 880.7137680253917, 900.3202582050551, 911.5988964855467, 17206.569835754097, 17696.292148314085, 17714.734278985827, 17738.733918991227, 17740.556486087855, 16516.339346426128, 16611.750823737653, 16667.16664166793, 16681.749773753403, 16682.49952501424, 738.9113306403226, 759.9468037237395, 762.2529920677739, 763.7441457111875, 869.0397111192104, 895.2524641924001, 899.6716198587501, 905.6196397513061, 906.3066753933247, 908.0919080919081, 951.5147274889821, 952.0717300631864, 952.5856252530172, 952.6284748947916, 952.6808519146094, 946.9337146399755, 948.6395169835488, 951.490952340498, 954.1365064616533, 954.9188318992894, 702.6276073680957, 703.5180901082723, 704.3625518905399, 704.5244459989538, 704.6195054670471, 14486.348114334774, 14566.198859062748, 14622.561323160322, 14647.560573182822, 14662.0, 16362.0, 16872.493825185265, 17077.40229091976, 17116.400925967533, 17949.192286347014, 13614.863851361459, 13622.727545449035, 13666.863331366658, 14013.719725605431, 14028.85971140296, 3925.1757131002237, 3971.4241434991764, 3994.0613955720673, 4016.136530645896, 4105.137921036586, 89797.55101224453, 91347.0, 91411.54294228619, 91462.0, 93565.0, 12145.757084858253, 12314.692132696755, 12364.56724014655, 12512.124151309321, 12556.372181390894, 24653.260402187963, 24839.87580062124, 24910.0, 24981.375465613506, 25000.37499062538, 8553.871691924625, 8607.096254893271, 8961.23829474502, 9072.455652660861, 9401.529923503798, 5359.249705041298, 5372.1941708744, 5379.35447746273, 5493.148561972901, 5556.97221513898, 3774.2640185163627, 3866.4007078902814, 3903.6096390361304, 3905.3165696003084, 3914.4911161548816, 637.0343174951266, 642.5662677692633, 651.8663673946829, 651.947844172465, 658.1904257762883, 682.5631595778706, 707.0243064570859, 734.1960553194244, 747.9663415146332, 818.4925346285341, 8773.254273386707, 9307.255419566418, 9614.08666176726, 9940.66831449284, 10052.748681283027, 633.8890694128509, 699.8180473077036, 733.8935854301096, 807.7368520040023, 808.7625932323555, 4556.84051058218, 4603.5396460353695, 4824.155772739757, 4835.564799168092, 4865.318855360252, 115081.0, 119829.0, 119966.4001679986, 125321.0, 125809.0, 93810.0, 97791.0, 98601.0, 99308.0, 103775.0, 57763.711181443825, 79170.0, 79920.60039699764, 93501.0, 95376.0, 9424.785175483892, 9673.467959262309, 9680.515974201402, 9713.805723885624, 10007.699769006942, 57076.429235708776, 57198.42801571931, 58103.0, 58470.0, 59384.406155939614, 70869.0, 83405.58297208718, 83446.0, 84735.0, 85958.57020714856, 12709.237445753284, 12827.10210285271, 13287.13633613812, 13427.731445371233, 13573.864261357656, 14966.476173333882, 15381.846181538041, 16002.359905603646, 16015.67968640644, 16061.036337819769, 12989.545365912149, 13441.529546465827, 14203.928980355033, 14825.851741482447, 14968.925155374154, 14407.279636017947, 14676.853231467549, 14680.265986700835, 15070.397184112513, 15123.773143403077, 12670.493180272686, 12978.286194259406, 13349.199048057148, 13365.0, 13598.048136630736, 11435.313881166823, 12027.15809893299, 12117.81823272634, 12408.255504669749, 12801.487940482277, 11469.541218351505, 11895.583654572049, 12078.396080196131, 12395.62813115608, 12639.684007899876], "value": 8096.853399277146, "unit": "score"}], "name": "dromaeo_css", "value": 7688.93368682805, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 120.0, 110.0, 113.0, 111.0, 110.0, 111.0, 111.0, 110.0], "value": 111.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 140.0, 181.0, 115.0, 149.0, 143.0, 117.0, 170.0, 114.0], "value": 138.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 161.0, 161.0, 161.0, 161.0, 160.0, 159.0, 161.0, 162.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 74.0, 185.0, 76.0, 76.0, 75.0, 75.0, 169.0, 75.0], "value": 95.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 143.0, 82.0, 83.0, 83.0, 150.0, 83.0, 84.0, 150.0], "value": 102.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 147.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 49.0, 51.0, 52.0, 50.0, 51.0, 52.0, 51.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 60.0, 60.0, 59.0, 59.0, 60.0, 59.0, 60.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 74.0, 83.0, 72.0, 73.0, 73.0, 82.0, 73.0, 72.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 117.0, 116.0, 125.0, 126.0, 116.0, 114.0, 123.0, 159.0, 128.0], "value": 124.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 154.0, 151.0, 148.0, 151.0, 150.0, 150.0, 154.0, 152.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 63.0, 61.0, 60.0, 60.0, 68.0, 63.0, 60.0, 61.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1489.4, "alertThreshold": 2.0}]} 18:17:12 INFO - Return code: 0 18:17:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:17:12 INFO - # TBPL SUCCESS # 18:17:12 INFO - Running post-action listener: _resource_record_post_action 18:17:12 INFO - [mozharness: 2016-09-24 01:17:12.462867Z] Finished run-tests step (success) 18:17:12 INFO - Running post-run listener: _resource_record_post_run 18:17:12 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 2265088; Write bytes: 289755136; Read time: 112; Write time: 318980 18:17:12 INFO - TinderboxPrint: CPU usage
12.9% 18:17:12 INFO - TinderboxPrint: I/O read bytes / time
2,265,088 / 112 18:17:12 INFO - TinderboxPrint: I/O write bytes / time
289,755,136 / 318,980 18:17:12 INFO - TinderboxPrint: CPU idle
8,258.6 (87.1%) 18:17:12 INFO - TinderboxPrint: CPU user
1,178.9 (12.4%) 18:17:12 INFO - TinderboxPrint: Swap in / out
0 / 0 18:17:12 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 71262208; Read time: 0; Write time: 145556 18:17:12 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 532480; Write bytes: 218492928; Read time: 80; Write time: 173424 18:17:13 INFO - Running post-run listener: _upload_blobber_files 18:17:13 INFO - Blob upload gear active. 18:17:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:17:13 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 18:17:13 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:17:13 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:17:13 INFO - (blobuploader) - INFO - Open directory for files ... 18:17:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:17:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:17:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:17:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:17:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:17:16 INFO - (blobuploader) - INFO - Done attempting. 18:17:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:17:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:17:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:17:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:17:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:17:17 INFO - (blobuploader) - INFO - Done attempting. 18:17:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:17:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:17:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:17:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:17:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:17:18 INFO - (blobuploader) - INFO - Done attempting. 18:17:18 INFO - (blobuploader) - INFO - Iteration through files over. 18:17:18 INFO - Return code: 0 18:17:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:17:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:17:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5c725f4e5a0086ff07e4fb2cfc60cc7ee8832c1360c4b51806ddf9c488a77d7f3f99172f880249311d08c79ac074d3bf4cfa9fd2930e10e58715e5af9e91287b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a761141b85e36befebe413422799a34d16d89945aeaff9f2e47bf19df96c6da60ba6946eb827a8ccec9f0667ed29889452c320e44077418d7d56c42cea67fc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d95a54bd448da3c7b3f9c042b3f4579b5190b696b371141e9a63af2cd7a6e879f6c0def691e917540bb94402a737257b77713aed7e3e77e3556b7aa2b13d3b7e"} 18:17:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:17:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:17:18 INFO - Contents: 18:17:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5c725f4e5a0086ff07e4fb2cfc60cc7ee8832c1360c4b51806ddf9c488a77d7f3f99172f880249311d08c79ac074d3bf4cfa9fd2930e10e58715e5af9e91287b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a761141b85e36befebe413422799a34d16d89945aeaff9f2e47bf19df96c6da60ba6946eb827a8ccec9f0667ed29889452c320e44077418d7d56c42cea67fc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d95a54bd448da3c7b3f9c042b3f4579b5190b696b371141e9a63af2cd7a6e879f6c0def691e917540bb94402a737257b77713aed7e3e77e3556b7aa2b13d3b7e"} 18:17:18 INFO - Running post-run listener: copy_logs_to_upload_dir 18:17:18 INFO - Copying logs to upload dir... 18:17:18 INFO - mkdir: /builds/slave/test/build/upload/logs 18:17:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.526218 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-09-23 18:17:18.316935) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 18:17:18.320052) ========= 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=fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429 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-Beta/sha512/5c725f4e5a0086ff07e4fb2cfc60cc7ee8832c1360c4b51806ddf9c488a77d7f3f99172f880249311d08c79ac074d3bf4cfa9fd2930e10e58715e5af9e91287b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a761141b85e36befebe413422799a34d16d89945aeaff9f2e47bf19df96c6da60ba6946eb827a8ccec9f0667ed29889452c320e44077418d7d56c42cea67fc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d95a54bd448da3c7b3f9c042b3f4579b5190b696b371141e9a63af2cd7a6e879f6c0def691e917540bb94402a737257b77713aed7e3e77e3556b7aa2b13d3b7e"} build_url:https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/BCJVe5vyTjq9rQVI1rVOfA/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5c725f4e5a0086ff07e4fb2cfc60cc7ee8832c1360c4b51806ddf9c488a77d7f3f99172f880249311d08c79ac074d3bf4cfa9fd2930e10e58715e5af9e91287b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8a761141b85e36befebe413422799a34d16d89945aeaff9f2e47bf19df96c6da60ba6946eb827a8ccec9f0667ed29889452c320e44077418d7d56c42cea67fc0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d95a54bd448da3c7b3f9c042b3f4579b5190b696b371141e9a63af2cd7a6e879f6c0def691e917540bb94402a737257b77713aed7e3e77e3556b7aa2b13d3b7e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-23 18:17:18.345114) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 18:17:18.345431) ========= 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=fa44defc139c4ba8574fe040000001db-1474678161.354882-1265189429 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004440 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-23 18:17:18.427698) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-23 18:17:18.428019) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-23 18:17:18.428335) ========= ========= Total master_lag: 0.15 =========