builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1477362364.22 results: success (0) buildid: 20161024163422 builduid: 4fa350c28d9f4227a9b28f3bbb1ac056 revision: 1d8f1c457fdf22f3cda9175ac966698046d3543a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:04.215536) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:04.216042) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:04.216334) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:04.231443) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:04.231809) ========= 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=0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-24 19:26:04-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-10-24 19:26:04 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.104689 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:04.350195) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:04.350529) ========= 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=0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021775 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:04.401814) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:04.402356) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1d8f1c457fdf22f3cda9175ac966698046d3543a --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 1d8f1c457fdf22f3cda9175ac966698046d3543a --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=0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-24 19:26:04,598 truncating revision to first 12 chars 2016-10-24 19:26:04,598 Setting DEBUG logging. 2016-10-24 19:26:04,598 attempt 1/10 2016-10-24 19:26:04,598 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1d8f1c457fdf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-24 19:26:04,912 unpacking tar archive at: mozilla-beta-1d8f1c457fdf/testing/mozharness/ program finished with exit code 0 elapsedTime=0.644541 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:05.060725) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:05.061045) ========= script_repo_revision: 1d8f1c457fdf22f3cda9175ac966698046d3543a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:05.061445) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:05.061707) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-24 19:26:05.069498) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-24 19:26:05.069870) ========= /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=0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:26:05 INFO - MultiFileLogger online at 20161024 19:26:05 in /builds/slave/test 19:26:05 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 19:26:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:26:05 INFO - {'append_to_log': False, 19:26:05 INFO - 'base_work_dir': '/builds/slave/test', 19:26:05 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:26:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:26:05 INFO - 'branch': 'Mozilla-Beta', 19:26:05 INFO - 'buildbot_json_path': 'buildprops.json', 19:26:05 INFO - 'config_files': ('talos/linux_config.py',), 19:26:05 INFO - 'default_actions': ('clobber', 19:26:05 INFO - 'read-buildbot-config', 19:26:05 INFO - 'download-and-extract', 19:26:05 INFO - 'populate-webroot', 19:26:05 INFO - 'create-virtualenv', 19:26:05 INFO - 'install', 19:26:05 INFO - 'run-tests'), 19:26:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:26:05 INFO - 'download_minidump_stackwalk': True, 19:26:05 INFO - 'download_symbols': 'ondemand', 19:26:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:26:05 INFO - 'tooltool.py': '/tools/tooltool.py', 19:26:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:26:05 INFO - '/tools/misc-python/virtualenv.py')}, 19:26:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:26:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:26:05 INFO - 'installer_path': 'installer.exe', 19:26:05 INFO - 'log_level': 'info', 19:26:05 INFO - 'log_name': 'talos', 19:26:05 INFO - 'log_to_console': True, 19:26:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:26:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:26:05 INFO - 'opt_config_files': (), 19:26:05 INFO - 'pip_index': False, 19:26:05 INFO - 'sps_profile': False, 19:26:05 INFO - 'sps_profile_interval': 0, 19:26:05 INFO - 'suite': 'dromaeojs', 19:26:05 INFO - 'system_bits': '32', 19:26:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:26:05 INFO - 'title': 'talos-linux64-ix-017', 19:26:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:26:05 INFO - 'use_talos_json': True, 19:26:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:26:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:26:05 INFO - 'work_dir': 'build'} 19:26:05 INFO - [mozharness: 2016-10-25 02:26:05.356440Z] Running clobber step. 19:26:05 INFO - Running pre-action listener: _resource_record_pre_action 19:26:05 INFO - Running main action method: clobber 19:26:05 INFO - rmtree: /builds/slave/test/build 19:26:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:26:06 INFO - Running post-action listener: _resource_record_post_action 19:26:06 INFO - [mozharness: 2016-10-25 02:26:06.371274Z] Finished clobber step (success) 19:26:06 INFO - [mozharness: 2016-10-25 02:26:06.371373Z] Running read-buildbot-config step. 19:26:06 INFO - Running pre-action listener: _resource_record_pre_action 19:26:06 INFO - Running main action method: read_buildbot_config 19:26:06 INFO - Using buildbot properties: 19:26:06 INFO - { 19:26:06 INFO - "project": "", 19:26:06 INFO - "product": "firefox", 19:26:06 INFO - "script_repo_revision": "production", 19:26:06 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 19:26:06 INFO - "repository": "", 19:26:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 19:26:06 INFO - "buildid": "20161024163422", 19:26:06 INFO - "pgo_build": "True", 19:26:06 INFO - "basedir": "/builds/slave/test", 19:26:06 INFO - "buildnumber": 440, 19:26:06 INFO - "slavename": "talos-linux64-ix-017", 19:26:06 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 19:26:06 INFO - "platform": "ubuntu64_hw", 19:26:06 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 19:26:06 INFO - "branch": "mozilla-beta", 19:26:06 INFO - "repo_path": "releases/mozilla-beta", 19:26:06 INFO - "revision": "1d8f1c457fdf22f3cda9175ac966698046d3543a", 19:26:06 INFO - "stage_platform": "linux64-pgo", 19:26:06 INFO - "builduid": "4fa350c28d9f4227a9b28f3bbb1ac056", 19:26:06 INFO - "slavebuilddir": "test" 19:26:06 INFO - } 19:26:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 19:26:06 INFO - Running post-action listener: _resource_record_post_action 19:26:06 INFO - [mozharness: 2016-10-25 02:26:06.373821Z] Finished read-buildbot-config step (success) 19:26:06 INFO - [mozharness: 2016-10-25 02:26:06.373911Z] Running download-and-extract step. 19:26:06 INFO - Running pre-action listener: _resource_record_pre_action 19:26:06 INFO - Running main action method: download_and_extract 19:26:06 INFO - mkdir: /builds/slave/test/build/tests 19:26:06 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')]} 19:26:06 INFO - https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:26:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:26:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:26:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:26:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:26:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:26:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:26:06 INFO - retry: Failed, sleeping 30 seconds before retrying 19:26:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:26:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:26:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:26:36 INFO - retry: Failed, sleeping 60 seconds before retrying 19:27:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:27:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:27:36 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:27:36 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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! 19:27:36 INFO - Caught exception: HTTP Error 404: Not Found 19:27:36 INFO - Caught exception: HTTP Error 404: Not Found 19:27:36 INFO - Caught exception: HTTP Error 404: Not Found 19:27:36 INFO - trying https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:27:36 INFO - Downloading https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:27:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:27:37 INFO - Downloaded 1327 bytes. 19:27:37 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 19:27:37 INFO - Using the following test package requirements: 19:27:37 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 19:27:37 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:27:37 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 19:27:37 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:27:37 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 19:27:37 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:27:37 INFO - u'jsshell-linux-x86_64.zip'], 19:27:37 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:27:37 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 19:27:37 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 19:27:37 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:27:37 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 19:27:37 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:27:37 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 19:27:37 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:27:37 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 19:27:37 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 19:27:37 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 19:27:37 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:27:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:27:37 INFO - https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:27:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:27:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:27:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:27:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:27:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:27:37 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:27:37 INFO - retry: Failed, sleeping 30 seconds before retrying 19:28:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:28:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:28:07 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:28:07 INFO - retry: Failed, sleeping 60 seconds before retrying 19:29:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:29:07 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:29:07 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:29:07 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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! 19:29:07 INFO - Caught exception: HTTP Error 404: Not Found 19:29:07 INFO - Caught exception: HTTP Error 404: Not Found 19:29:07 INFO - Caught exception: HTTP Error 404: Not Found 19:29:07 INFO - trying https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:29:07 INFO - Downloading https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:29:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:29:08 INFO - Downloaded 21774914 bytes. 19:29:08 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'] 19:29:08 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 19:29:09 INFO - Return code: 0 19:29:09 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 19:29:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:29:09 INFO - https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:29:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:29:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:29:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:29:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:29:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:29:09 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:29:09 INFO - retry: Failed, sleeping 30 seconds before retrying 19:29:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:29:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:29:39 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:29:39 INFO - retry: Failed, sleeping 60 seconds before retrying 19:30:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:30:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:30:39 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:30:39 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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! 19:30:39 INFO - Caught exception: HTTP Error 404: Not Found 19:30:39 INFO - Caught exception: HTTP Error 404: Not Found 19:30:39 INFO - Caught exception: HTTP Error 404: Not Found 19:30:39 INFO - trying https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 19:30:39 INFO - Downloading https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:30:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:30:40 INFO - Downloaded 21774914 bytes. 19:30:40 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'] 19:30:40 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 19:30:41 INFO - Return code: 0 19:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:30:41 INFO - https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:30:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:30:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:30:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:30:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:30:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:30:41 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:30:41 INFO - retry: Failed, sleeping 30 seconds before retrying 19:31:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:31:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:31:11 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:31:11 INFO - retry: Failed, sleeping 60 seconds before retrying 19:32:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:32:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:32:11 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:32:11 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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! 19:32:11 INFO - Caught exception: HTTP Error 404: Not Found 19:32:11 INFO - Caught exception: HTTP Error 404: Not Found 19:32:11 INFO - Caught exception: HTTP Error 404: Not Found 19:32:11 INFO - trying https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 19:32:11 INFO - Downloading https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:32:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:32:12 INFO - Downloaded 14413762 bytes. 19:32:12 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'] 19:32:12 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 19:32:12 INFO - Return code: 0 19:32: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')]} 19:32:12 INFO - https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:32:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:32:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:32:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:32:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:32:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:32:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 19:32:12 INFO - retry: Failed, sleeping 30 seconds before retrying 19:32:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:32:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:32:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 19:32:42 INFO - retry: Failed, sleeping 60 seconds before retrying 19:33:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:33:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:33:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 19:33:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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! 19:33:42 INFO - Caught exception: HTTP Error 404: Not Found 19:33:42 INFO - Caught exception: HTTP Error 404: Not Found 19:33:42 INFO - Caught exception: HTTP Error 404: Not Found 19:33:42 INFO - trying https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:33:42 INFO - Downloading https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:33:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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 19:33:43 INFO - Downloaded 56798244 bytes. 19:33:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:33:43 INFO - mkdir: /builds/slave/test/properties 19:33:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:33:43 INFO - Writing to file /builds/slave/test/properties/build_url 19:33:43 INFO - Contents: 19:33:43 INFO - build_url:https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 19:33:44 INFO - Running post-action listener: _resource_record_post_action 19:33:44 INFO - Running post-action listener: set_extra_try_arguments 19:33:44 INFO - [mozharness: 2016-10-25 02:33:44.653319Z] Finished download-and-extract step (success) 19:33:44 INFO - [mozharness: 2016-10-25 02:33:44.653550Z] Running populate-webroot step. 19:33:44 INFO - Running pre-action listener: _resource_record_pre_action 19:33:44 INFO - Running main action method: populate_webroot 19:33:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:33:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:33:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:33:44 INFO - '--tppagecycles', 19:33:44 INFO - '7'], 19:33:44 INFO - 'tests': ['tsvgm']}}, 19:33:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:33:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:33:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:33:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:33:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:33:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:33:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:44 INFO - 'talos_options': ['--disable-e10s'], 19:33:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:33:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:33:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:44 INFO - 'talos_options': ['--disable-e10s'], 19:33:44 INFO - 'tests': ['damp', 'tps']}, 19:33:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:44 INFO - 'tests': ['damp', 'tps']}, 19:33:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:33:44 INFO - 'tests': ['dromaeo_dom']}, 19:33:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:33:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:33:44 INFO - 'tests': ['basic_compositor_video']}, 19:33:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:33:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:33:44 INFO - 'tests': ['a11yr', 19:33:44 INFO - 'ts_paint', 19:33:44 INFO - 'tpaint', 19:33:44 INFO - 'sessionrestore', 19:33:44 INFO - 'sessionrestore_no_auto_restore', 19:33:44 INFO - 'tabpaint']}, 19:33:44 INFO - 'other-e10s': {'tests': ['a11yr', 19:33:44 INFO - 'ts_paint', 19:33:44 INFO - 'tpaint', 19:33:44 INFO - 'sessionrestore', 19:33:44 INFO - 'sessionrestore_no_auto_restore', 19:33:44 INFO - 'tabpaint']}, 19:33:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:33:44 INFO - 'ts_paint', 19:33:44 INFO - 'tpaint', 19:33:44 INFO - 'sessionrestore', 19:33:44 INFO - 'sessionrestore_no_auto_restore', 19:33:44 INFO - 'tabpaint']}, 19:33:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:33:44 INFO - 'ts_paint', 19:33:44 INFO - 'tpaint', 19:33:44 INFO - 'sessionrestore', 19:33:44 INFO - 'sessionrestore_no_auto_restore', 19:33:44 INFO - 'tabpaint']}, 19:33:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:33:44 INFO - 'tests': ['a11yr', 19:33:44 INFO - 'ts_paint', 19:33:44 INFO - 'tpaint', 19:33:44 INFO - 'sessionrestore', 19:33:44 INFO - 'sessionrestore_no_auto_restore', 19:33:44 INFO - 'tabpaint']}, 19:33:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:33:44 INFO - 'tests': ['a11yr', 19:33:44 INFO - 'ts_paint', 19:33:44 INFO - 'tpaint', 19:33:44 INFO - 'sessionrestore', 19:33:44 INFO - 'sessionrestore_no_auto_restore', 19:33:44 INFO - 'tabpaint']}, 19:33:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:33:44 INFO - 'tests': ['tsvgx', 19:33:44 INFO - 'tsvgr_opacity', 19:33:44 INFO - 'tart', 19:33:44 INFO - 'tscrollx', 19:33:44 INFO - 'cart']}, 19:33:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:33:44 INFO - 'tsvgr_opacity', 19:33:44 INFO - 'tart', 19:33:44 INFO - 'tscrollx', 19:33:44 INFO - 'cart']}, 19:33:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:44 INFO - 'talos_options': ['--disable-e10s'], 19:33:44 INFO - 'tests': ['tp5o']}, 19:33:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:44 INFO - 'tests': ['tp5o']}, 19:33:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:44 INFO - 'talos_options': ['--disable-e10s', 19:33:44 INFO - '--xperf_path', 19:33:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:33:44 INFO - 'tests': ['tp5n']}, 19:33:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:44 INFO - 'talos_options': ['--xperf_path', 19:33:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:33:44 INFO - 'tests': ['tp5n']}}, 19:33:44 INFO - 'talos.zip': {'path': '', 19:33:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:33:44 INFO - Running post-action listener: _resource_record_post_action 19:33:44 INFO - [mozharness: 2016-10-25 02:33:44.675836Z] Finished populate-webroot step (success) 19:33:44 INFO - [mozharness: 2016-10-25 02:33:44.675925Z] Running create-virtualenv step. 19:33:44 INFO - Running pre-action listener: _resource_record_pre_action 19:33:44 INFO - Running main action method: create_virtualenv 19:33:44 INFO - Creating virtualenv /builds/slave/test/build/venv 19:33:44 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 19:33:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:33:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:33:44 INFO - Using real prefix '/usr' 19:33:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:33:45 INFO - Installing distribute.............................................................................................................................................................................................done. 19:33:47 INFO - Installing pip.................done. 19:33:47 INFO - Return code: 0 19:33:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:47 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 0x1614030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d33b0>, '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 0x18d42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d4740>, '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': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', '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 19:33:47 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 19:33:47 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 19:33:47 INFO - Using env: {'DISPLAY': ':0', 19:33:47 INFO - 'HOME': '/home/cltbld', 19:33:47 INFO - 'LANG': 'en_US.UTF-8', 19:33:47 INFO - 'LANGUAGE': 'en_US:en', 19:33:47 INFO - 'LOGNAME': 'cltbld', 19:33:47 INFO - 'MAIL': '/var/mail/cltbld', 19:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:47 INFO - 'MOZ_NO_REMOTE': '1', 19:33:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:47 INFO - 'NO_EM_RESTART': '1', 19:33:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:47 INFO - 'PWD': '/builds/slave/test', 19:33:47 INFO - 'SHELL': '/bin/bash', 19:33:47 INFO - 'SHLVL': '1', 19:33:47 INFO - 'TERM': 'linux', 19:33:47 INFO - 'TMOUT': '86400', 19:33:47 INFO - 'USER': 'cltbld', 19:33:47 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', 19:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:47 INFO - '_': '/tools/buildbot/bin/python'} 19:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:47 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 19:33:47 INFO - Cleaning up... 19:33:47 INFO - Return code: 0 19:33:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:47 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 0x1614030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d33b0>, '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 0x18d42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d4740>, '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': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', '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 19:33:47 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 19:33:47 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 19:33:47 INFO - Using env: {'DISPLAY': ':0', 19:33:47 INFO - 'HOME': '/home/cltbld', 19:33:47 INFO - 'LANG': 'en_US.UTF-8', 19:33:47 INFO - 'LANGUAGE': 'en_US:en', 19:33:47 INFO - 'LOGNAME': 'cltbld', 19:33:47 INFO - 'MAIL': '/var/mail/cltbld', 19:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:47 INFO - 'MOZ_NO_REMOTE': '1', 19:33:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:47 INFO - 'NO_EM_RESTART': '1', 19:33:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:47 INFO - 'PWD': '/builds/slave/test', 19:33:47 INFO - 'SHELL': '/bin/bash', 19:33:47 INFO - 'SHLVL': '1', 19:33:47 INFO - 'TERM': 'linux', 19:33:47 INFO - 'TMOUT': '86400', 19:33:47 INFO - 'USER': 'cltbld', 19:33:47 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', 19:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:47 INFO - '_': '/tools/buildbot/bin/python'} 19:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:47 INFO - Downloading/unpacking psutil>=3.1.1 19:33:47 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 19:33:47 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 19:33:47 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 19:33:47 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 19:33:47 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 19:33:47 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 19:33:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:33:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:33:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:33:49 INFO - Installing collected packages: psutil 19:33:49 INFO - Running setup.py install for psutil 19:33:49 INFO - building 'psutil._psutil_linux' extension 19:33:49 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 19:33:49 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 19:33:49 INFO - building 'psutil._psutil_posix' extension 19:33:49 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 19:33:50 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 19:33:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:33:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:33:50 INFO - Successfully installed psutil 19:33:50 INFO - Cleaning up... 19:33:50 INFO - Return code: 0 19:33:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:50 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 0x1614030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d33b0>, '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 0x18d42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d4740>, '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': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', '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 19:33:50 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 19:33:50 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 19:33:50 INFO - Using env: {'DISPLAY': ':0', 19:33:50 INFO - 'HOME': '/home/cltbld', 19:33:50 INFO - 'LANG': 'en_US.UTF-8', 19:33:50 INFO - 'LANGUAGE': 'en_US:en', 19:33:50 INFO - 'LOGNAME': 'cltbld', 19:33:50 INFO - 'MAIL': '/var/mail/cltbld', 19:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:50 INFO - 'MOZ_NO_REMOTE': '1', 19:33:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:50 INFO - 'NO_EM_RESTART': '1', 19:33:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:50 INFO - 'PWD': '/builds/slave/test', 19:33:50 INFO - 'SHELL': '/bin/bash', 19:33:50 INFO - 'SHLVL': '1', 19:33:50 INFO - 'TERM': 'linux', 19:33:50 INFO - 'TMOUT': '86400', 19:33:50 INFO - 'USER': 'cltbld', 19:33:50 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', 19:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:50 INFO - '_': '/tools/buildbot/bin/python'} 19:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:33:50 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 19:33:50 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 19:33:50 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 19:33:50 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 19:33:50 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 19:33:50 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 19:33:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:33:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:33:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:33:52 INFO - Installing collected packages: mozsystemmonitor 19:33:52 INFO - Running setup.py install for mozsystemmonitor 19:33:52 INFO - Successfully installed mozsystemmonitor 19:33:52 INFO - Cleaning up... 19:33:52 INFO - Return code: 0 19:33:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:33:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:52 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 0x1614030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d33b0>, '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 0x18d42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d4740>, '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': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', '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 19:33:52 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 19:33:52 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 19:33:52 INFO - Using env: {'DISPLAY': ':0', 19:33:52 INFO - 'HOME': '/home/cltbld', 19:33:52 INFO - 'LANG': 'en_US.UTF-8', 19:33:52 INFO - 'LANGUAGE': 'en_US:en', 19:33:52 INFO - 'LOGNAME': 'cltbld', 19:33:52 INFO - 'MAIL': '/var/mail/cltbld', 19:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:52 INFO - 'MOZ_NO_REMOTE': '1', 19:33:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:52 INFO - 'NO_EM_RESTART': '1', 19:33:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:52 INFO - 'PWD': '/builds/slave/test', 19:33:52 INFO - 'SHELL': '/bin/bash', 19:33:52 INFO - 'SHLVL': '1', 19:33:52 INFO - 'TERM': 'linux', 19:33:52 INFO - 'TMOUT': '86400', 19:33:52 INFO - 'USER': 'cltbld', 19:33:52 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', 19:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:52 INFO - '_': '/tools/buildbot/bin/python'} 19:33:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:52 INFO - Downloading/unpacking jsonschema==2.5.1 19:33:52 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 19:33:52 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 19:33:52 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 19:33:52 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 19:33:52 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 19:33:52 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 19:33:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:33:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:54 INFO - Downloading functools32-3.2.3-2.tar.gz 19:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:33:54 INFO - warning: no files found matching '*.txt' 19:33:54 INFO - no previously-included directories found matching 'build' 19:33:54 INFO - no previously-included directories found matching 'dist' 19:33:54 INFO - no previously-included directories found matching '.git*' 19:33:54 INFO - Installing collected packages: jsonschema, functools32 19:33:55 INFO - Running setup.py install for functools32 19:33:55 INFO - warning: no files found matching '*.txt' 19:33:55 INFO - no previously-included directories found matching 'build' 19:33:55 INFO - no previously-included directories found matching 'dist' 19:33:55 INFO - no previously-included directories found matching '.git*' 19:33:55 INFO - Successfully installed jsonschema functools32 19:33:55 INFO - Cleaning up... 19:33:55 INFO - Return code: 0 19:33:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:33: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')]} 19:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33: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')]} 19:33:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:55 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 0x1614030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d33b0>, '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 0x18d42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d4740>, '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': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', '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 19:33:55 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 19:33:55 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 19:33:55 INFO - Using env: {'DISPLAY': ':0', 19:33:55 INFO - 'HOME': '/home/cltbld', 19:33:55 INFO - 'LANG': 'en_US.UTF-8', 19:33:55 INFO - 'LANGUAGE': 'en_US:en', 19:33:55 INFO - 'LOGNAME': 'cltbld', 19:33:55 INFO - 'MAIL': '/var/mail/cltbld', 19:33:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:55 INFO - 'MOZ_NO_REMOTE': '1', 19:33:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:55 INFO - 'NO_EM_RESTART': '1', 19:33:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:55 INFO - 'PWD': '/builds/slave/test', 19:33:55 INFO - 'SHELL': '/bin/bash', 19:33:55 INFO - 'SHLVL': '1', 19:33:55 INFO - 'TERM': 'linux', 19:33:55 INFO - 'TMOUT': '86400', 19:33:55 INFO - 'USER': 'cltbld', 19:33:55 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', 19:33:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:55 INFO - '_': '/tools/buildbot/bin/python'} 19:33:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:33:55 INFO - Cleaning up... 19:33:55 INFO - Return code: 0 19:33:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:33: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')]} 19:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33: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')]} 19:33:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:55 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 0x1614030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d33b0>, '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 0x18d42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d4740>, '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': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', '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 19:33:55 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 19:33:55 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 19:33:55 INFO - Using env: {'DISPLAY': ':0', 19:33:55 INFO - 'HOME': '/home/cltbld', 19:33:55 INFO - 'LANG': 'en_US.UTF-8', 19:33:55 INFO - 'LANGUAGE': 'en_US:en', 19:33:55 INFO - 'LOGNAME': 'cltbld', 19:33:55 INFO - 'MAIL': '/var/mail/cltbld', 19:33:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:55 INFO - 'MOZ_NO_REMOTE': '1', 19:33:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:55 INFO - 'NO_EM_RESTART': '1', 19:33:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:55 INFO - 'PWD': '/builds/slave/test', 19:33:55 INFO - 'SHELL': '/bin/bash', 19:33:55 INFO - 'SHLVL': '1', 19:33:55 INFO - 'TERM': 'linux', 19:33:55 INFO - 'TMOUT': '86400', 19:33:55 INFO - 'USER': 'cltbld', 19:33:55 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', 19:33:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:55 INFO - '_': '/tools/buildbot/bin/python'} 19:33:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:55 INFO - Downloading/unpacking blobuploader==1.2.4 19:33:55 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 19:33:55 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 19:33:55 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 19:33:55 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 19:33:55 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 19:33:55 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 19:33:57 INFO - Downloading blobuploader-1.2.4.tar.gz 19:33:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:33:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:33:57 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 19:33:57 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 19:33:57 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 19:33:57 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 19:33:57 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 19:33:57 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 19:33:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:33:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:33:58 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 19:33:58 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 19:33:58 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 19:33:58 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 19:33:58 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 19:33:58 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 19:33:58 INFO - Downloading docopt-0.6.1.tar.gz 19:33:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:33:58 INFO - Installing collected packages: blobuploader, requests, docopt 19:33:58 INFO - Running setup.py install for blobuploader 19:33:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:33:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:33:58 INFO - Running setup.py install for requests 19:33:58 INFO - Running setup.py install for docopt 19:33:58 INFO - Successfully installed blobuploader requests docopt 19:33:58 INFO - Cleaning up... 19:33:58 INFO - Return code: 0 19:33:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:33:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:58 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 0x1614030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d33b0>, '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 0x18d42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d4740>, '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': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', '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 19:33:58 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 19:33:58 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 19:33:58 INFO - Using env: {'DISPLAY': ':0', 19:33:58 INFO - 'HOME': '/home/cltbld', 19:33:58 INFO - 'LANG': 'en_US.UTF-8', 19:33:58 INFO - 'LANGUAGE': 'en_US:en', 19:33:58 INFO - 'LOGNAME': 'cltbld', 19:33:58 INFO - 'MAIL': '/var/mail/cltbld', 19:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:58 INFO - 'MOZ_NO_REMOTE': '1', 19:33:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:33:58 INFO - 'NO_EM_RESTART': '1', 19:33:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:58 INFO - 'PWD': '/builds/slave/test', 19:33:58 INFO - 'SHELL': '/bin/bash', 19:33:58 INFO - 'SHLVL': '1', 19:33:58 INFO - 'TERM': 'linux', 19:33:58 INFO - 'TMOUT': '86400', 19:33:58 INFO - 'USER': 'cltbld', 19:33:58 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', 19:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:58 INFO - '_': '/tools/buildbot/bin/python'} 19:33:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:33:59 INFO - Running setup.py (path:/tmp/pip-5zFq7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:33:59 INFO - Running setup.py (path:/tmp/pip-CSn0sZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:33:59 INFO - Running setup.py (path:/tmp/pip-kiDc7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:33:59 INFO - Running setup.py (path:/tmp/pip-TMXxRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:33:59 INFO - Running setup.py (path:/tmp/pip-qL5NlH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:33:59 INFO - Running setup.py (path:/tmp/pip-Qd2qcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:33:59 INFO - Running setup.py (path:/tmp/pip-i2LLrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:33:59 INFO - Running setup.py (path:/tmp/pip-1ZwrDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:33:59 INFO - Running setup.py (path:/tmp/pip-Vj5Yfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:33:59 INFO - Running setup.py (path:/tmp/pip-bhs9KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:33:59 INFO - Running setup.py (path:/tmp/pip-TADFBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:33:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:33:59 INFO - Running setup.py (path:/tmp/pip-ZIYlR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:34:00 INFO - Running setup.py (path:/tmp/pip-Url0lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:34:00 INFO - Running setup.py (path:/tmp/pip-ZM_P1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:34:00 INFO - Running setup.py (path:/tmp/pip-L8x34x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:34:00 INFO - Running setup.py (path:/tmp/pip-r49vL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:34:00 INFO - Running setup.py (path:/tmp/pip-jt8f9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:34:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:34:00 INFO - Running setup.py install for manifestparser 19:34:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:34:00 INFO - Running setup.py install for mozcrash 19:34:00 INFO - Running setup.py install for mozdebug 19:34:00 INFO - Running setup.py install for mozdevice 19:34:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:34:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:34:00 INFO - Running setup.py install for mozfile 19:34:00 INFO - Running setup.py install for mozhttpd 19:34:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Running setup.py install for mozinfo 19:34:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Running setup.py install for mozInstall 19:34:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Running setup.py install for mozleak 19:34:01 INFO - Running setup.py install for mozlog 19:34:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Running setup.py install for moznetwork 19:34:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Running setup.py install for mozprocess 19:34:01 INFO - Running setup.py install for mozprofile 19:34:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Running setup.py install for mozrunner 19:34:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:34:01 INFO - Running setup.py install for mozscreenshot 19:34:01 INFO - Running setup.py install for moztest 19:34:02 INFO - Running setup.py install for mozversion 19:34:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:34:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:34:02 INFO - Cleaning up... 19:34:02 INFO - Return code: 0 19:34:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:34:02 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')]} 19:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:02 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')]} 19:34:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:02 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 0x1614030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d33b0>, '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 0x18d42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d4740>, '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': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', '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 19:34:02 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 19:34:02 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 19:34:02 INFO - Using env: {'DISPLAY': ':0', 19:34:02 INFO - 'HOME': '/home/cltbld', 19:34:02 INFO - 'LANG': 'en_US.UTF-8', 19:34:02 INFO - 'LANGUAGE': 'en_US:en', 19:34:02 INFO - 'LOGNAME': 'cltbld', 19:34:02 INFO - 'MAIL': '/var/mail/cltbld', 19:34:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:02 INFO - 'MOZ_NO_REMOTE': '1', 19:34:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:34:02 INFO - 'NO_EM_RESTART': '1', 19:34:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:34:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:02 INFO - 'PWD': '/builds/slave/test', 19:34:02 INFO - 'SHELL': '/bin/bash', 19:34:02 INFO - 'SHLVL': '1', 19:34:02 INFO - 'TERM': 'linux', 19:34:02 INFO - 'TMOUT': '86400', 19:34:02 INFO - 'USER': 'cltbld', 19:34:02 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', 19:34:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:02 INFO - '_': '/tools/buildbot/bin/python'} 19:34:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:34:02 INFO - Running setup.py (path:/tmp/pip-U25oPB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:34:02 INFO - Running setup.py (path:/tmp/pip-CzOTqs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:34:02 INFO - Running setup.py (path:/tmp/pip-I1acdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:34:02 INFO - Running setup.py (path:/tmp/pip-fOA3IX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:34:02 INFO - Running setup.py (path:/tmp/pip-PACoQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:34:02 INFO - Running setup.py (path:/tmp/pip-6cl5fc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:34:02 INFO - Running setup.py (path:/tmp/pip-QNBu4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:34:02 INFO - Running setup.py (path:/tmp/pip-JbbR1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:34:02 INFO - Running setup.py (path:/tmp/pip-UippN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:34:03 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)) 19:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:34:03 INFO - Running setup.py (path:/tmp/pip-UcUhWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:34:03 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)) 19:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:34:03 INFO - Running setup.py (path:/tmp/pip-J0o0Ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:34:03 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)) 19:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:34:03 INFO - Running setup.py (path:/tmp/pip-9hNwVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:34:03 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)) 19:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:34:03 INFO - Running setup.py (path:/tmp/pip-KaHr5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:34:03 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)) 19:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:34:03 INFO - Running setup.py (path:/tmp/pip-eICDGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:34:03 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)) 19:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:34:03 INFO - Running setup.py (path:/tmp/pip-5qGBlz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:34:03 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)) 19:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:34:03 INFO - Running setup.py (path:/tmp/pip-K5rcR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:34:03 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)) 19:34:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:34:03 INFO - Running setup.py (path:/tmp/pip-zFmhG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:34:03 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)) 19:34:03 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)) 19:34:03 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)) 19:34:03 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)) 19:34:03 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)) 19:34:03 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)) 19:34:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:34:03 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 19:34:03 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 19:34:03 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 19:34:03 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 19:34:03 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 19:34:03 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 19:34:05 INFO - Downloading blessings-1.6.tar.gz 19:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:34:05 INFO - Installing collected packages: blessings 19:34:05 INFO - Running setup.py install for blessings 19:34:05 INFO - Successfully installed blessings 19:34:05 INFO - Cleaning up... 19:34:05 INFO - Return code: 0 19:34:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:34:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:34:06 INFO - Reading from file tmpfile_stdout 19:34:06 INFO - Current package versions: 19:34:06 INFO - argparse == 1.2.1 19:34:06 INFO - blessings == 1.6 19:34:06 INFO - blobuploader == 1.2.4 19:34:06 INFO - docopt == 0.6.1 19:34:06 INFO - functools32 == 3.2.3-2 19:34:06 INFO - jsonschema == 2.5.1 19:34:06 INFO - manifestparser == 1.1 19:34:06 INFO - mozInstall == 1.12 19:34:06 INFO - mozcrash == 0.17 19:34:06 INFO - mozdebug == 0.1 19:34:06 INFO - mozdevice == 0.48 19:34:06 INFO - mozfile == 1.2 19:34:06 INFO - mozhttpd == 0.7 19:34:06 INFO - mozinfo == 0.9 19:34:06 INFO - mozleak == 0.1 19:34:06 INFO - mozlog == 3.3 19:34:06 INFO - moznetwork == 0.27 19:34:06 INFO - mozprocess == 0.23 19:34:06 INFO - mozprofile == 0.28 19:34:06 INFO - mozrunner == 6.12 19:34:06 INFO - mozscreenshot == 0.1 19:34:06 INFO - mozsystemmonitor == 0.3 19:34:06 INFO - moztest == 0.7 19:34:06 INFO - mozversion == 1.4 19:34:06 INFO - psutil == 3.1.1 19:34:06 INFO - requests == 1.2.3 19:34:06 INFO - wsgiref == 0.1.2 19:34:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:34:06 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')]} 19:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:06 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')]} 19:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:06 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 0x1614030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d33b0>, '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 0x18d42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d4740>, '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': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', '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 19:34:06 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 19:34:06 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 19:34:06 INFO - Using env: {'DISPLAY': ':0', 19:34:06 INFO - 'HOME': '/home/cltbld', 19:34:06 INFO - 'LANG': 'en_US.UTF-8', 19:34:06 INFO - 'LANGUAGE': 'en_US:en', 19:34:06 INFO - 'LOGNAME': 'cltbld', 19:34:06 INFO - 'MAIL': '/var/mail/cltbld', 19:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:06 INFO - 'MOZ_NO_REMOTE': '1', 19:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:34:06 INFO - 'NO_EM_RESTART': '1', 19:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:06 INFO - 'PWD': '/builds/slave/test', 19:34:06 INFO - 'SHELL': '/bin/bash', 19:34:06 INFO - 'SHLVL': '1', 19:34:06 INFO - 'TERM': 'linux', 19:34:06 INFO - 'TMOUT': '86400', 19:34:06 INFO - 'USER': 'cltbld', 19:34:06 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', 19:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:06 INFO - '_': '/tools/buildbot/bin/python'} 19:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:06 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)) 19:34:06 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)) 19:34:06 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)) 19:34:06 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)) 19:34:06 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)) 19:34:06 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)) 19:34:06 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)) 19:34:06 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)) 19:34:06 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)) 19:34:06 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)) 19:34:06 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)) 19:34:06 INFO - Cleaning up... 19:34:06 INFO - Return code: 0 19:34:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:34:06 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')]} 19:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:06 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')]} 19:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:06 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 0x1614030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17778a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18d5180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d33b0>, '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 0x18d42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d4740>, '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': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', '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 19:34:06 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 19:34:06 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 19:34:06 INFO - Using env: {'DISPLAY': ':0', 19:34:06 INFO - 'HOME': '/home/cltbld', 19:34:06 INFO - 'LANG': 'en_US.UTF-8', 19:34:06 INFO - 'LANGUAGE': 'en_US:en', 19:34:06 INFO - 'LOGNAME': 'cltbld', 19:34:06 INFO - 'MAIL': '/var/mail/cltbld', 19:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:06 INFO - 'MOZ_NO_REMOTE': '1', 19:34:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:34:06 INFO - 'NO_EM_RESTART': '1', 19:34:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:06 INFO - 'PWD': '/builds/slave/test', 19:34:06 INFO - 'SHELL': '/bin/bash', 19:34:06 INFO - 'SHLVL': '1', 19:34:06 INFO - 'TERM': 'linux', 19:34:06 INFO - 'TMOUT': '86400', 19:34:06 INFO - 'USER': 'cltbld', 19:34:06 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', 19:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:06 INFO - '_': '/tools/buildbot/bin/python'} 19:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:34:06 INFO - Cleaning up... 19:34:06 INFO - Return code: 0 19:34:06 INFO - Running post-action listener: _resource_record_post_action 19:34:06 INFO - Running post-action listener: _start_resource_monitoring 19:34:06 INFO - Starting resource monitoring. 19:34:06 INFO - [mozharness: 2016-10-25 02:34:06.488161Z] Finished create-virtualenv step (success) 19:34:06 INFO - [mozharness: 2016-10-25 02:34:06.488591Z] Running install step. 19:34:06 INFO - Running pre-action listener: _resource_record_pre_action 19:34:06 INFO - Running main action method: install 19:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:34:06 INFO - Reading from file tmpfile_stdout 19:34:06 INFO - Detecting whether we're running mozinstall >=1.0... 19:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:34:06 INFO - Reading from file tmpfile_stdout 19:34:06 INFO - Output received: 19:34:06 INFO - Usage: mozinstall [options] installer 19:34:06 INFO - Options: 19:34:06 INFO - -h, --help show this help message and exit 19:34:06 INFO - -d DEST, --destination=DEST 19:34:06 INFO - Directory to install application into. [default: 19:34:06 INFO - "/builds/slave/test"] 19:34:06 INFO - --app=APP Application being installed. [default: firefox] 19:34:06 INFO - mkdir: /builds/slave/test/build/application 19:34:06 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'] 19:34:06 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 19:34:20 INFO - Reading from file tmpfile_stdout 19:34:20 INFO - Output received: 19:34:20 INFO - /builds/slave/test/build/application/firefox/firefox 19:34:20 INFO - Running post-action listener: _resource_record_post_action 19:34:20 INFO - [mozharness: 2016-10-25 02:34:20.776738Z] Finished install step (success) 19:34:20 INFO - [mozharness: 2016-10-25 02:34:20.777016Z] Running run-tests step. 19:34:20 INFO - Running pre-action listener: _resource_record_pre_action 19:34:20 INFO - Running main action method: run_tests 19:34:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:34:20 INFO - Python 2.7.3 19:34:20 INFO - Return code: 0 19:34:20 INFO - grabbing minidump binary from tooltool 19:34:20 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')]} 19:34:20 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 0x18d33b0>, '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 0x18d42b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d4740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:34:20 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 19:34:20 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 19:34:20 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 19:34:20 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:34:20 INFO - Return code: 0 19:34:20 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:34:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:34:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:34:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:34:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:34:20 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/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 19:34:20 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/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 19:34:20 INFO - Using env: {'DISPLAY': ':0', 19:34:20 INFO - 'HOME': '/home/cltbld', 19:34:20 INFO - 'LANG': 'en_US.UTF-8', 19:34:20 INFO - 'LANGUAGE': 'en_US:en', 19:34:20 INFO - 'LOGNAME': 'cltbld', 19:34:20 INFO - 'MAIL': '/var/mail/cltbld', 19:34:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:34:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:20 INFO - 'MOZ_NO_REMOTE': '1', 19:34:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:34:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:34:20 INFO - 'NO_EM_RESTART': '1', 19:34:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:20 INFO - 'PWD': '/builds/slave/test', 19:34:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:34:20 INFO - 'SHELL': '/bin/bash', 19:34:20 INFO - 'SHLVL': '1', 19:34:20 INFO - 'TERM': 'linux', 19:34:20 INFO - 'TMOUT': '86400', 19:34:20 INFO - 'USER': 'cltbld', 19:34:20 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206', 19:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:20 INFO - '_': '/tools/buildbot/bin/python'} 19:34:20 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/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 19:34:21 INFO - mozversion application_buildid: 20161024163422 19:34:21 INFO - mozversion application_changeset: 1d8f1c457fdf22f3cda9175ac966698046d3543a 19:34:21 INFO - mozversion application_display_name: Firefox 19:34:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:34:21 INFO - mozversion application_name: Firefox 19:34:21 INFO - mozversion application_remotingname: firefox 19:34:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:34:21 INFO - mozversion application_vendor: Mozilla 19:34:21 INFO - mozversion application_version: 50.0 19:34:21 INFO - mozversion platform_buildid: 20161024163422 19:34:21 INFO - mozversion platform_changeset: 1d8f1c457fdf22f3cda9175ac966698046d3543a 19:34:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:34:21 INFO - mozversion platform_version: 50.0 19:34:21 INFO - using testdate: 1477362861 19:34:21 INFO - actual date: 1477362861 19:34:21 INFO - starting webserver on 'localhost:41761' 19:34:21 INFO - SUITE-START | Running 2 tests 19:34:21 INFO - TEST-START | dromaeo_css 19:34:21 INFO - operating with platform_type : linux_ 19:34:21 INFO - Initialising browser for dromaeo_css test... 19:34:21 INFO - TEST-INFO | started process 26135 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuFAKOV/profile http://localhost:41761/getInfo.html) 19:34:24 INFO - PROCESS | 26135 | __metrics Screen width/height:1600/1200 19:34:24 INFO - PROCESS | 26135 | colorDepth:24 19:34:24 INFO - PROCESS | 26135 | Browser inner width/height: 1024/697 19:34:24 INFO - PROCESS | 26135 | __metrics 19:34:25 INFO - PROCESS | 26135 | 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" 19:34:25 INFO - PROCESS | 26135 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:34:25 INFO - PROCESS | 26135 | console.error: PushService: 19:34:25 INFO - PROCESS | 26135 | stateChangeProcessEnqueue: Error transitioning state 19:34:25 INFO - PROCESS | 26135 | UnknownError 19:34:26 INFO - TEST-INFO | 26135: exit 0 19:34:26 INFO - Browser initialized. 19:34:26 INFO - Running cycle 1/1 for dromaeo_css test... 19:34:26 INFO - TEST-INFO | started process 26222 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpuFAKOV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:34:38 INFO - PROCESS | 26222 | RSS: Main: 143593472 19:34:38 INFO - PROCESS | 26222 | 19:35:29 INFO - PROCESS | 26222 | 1477362929403 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] 19:37:37 INFO - PROCESS | 26222 | Cycle 1(1): loaded http://localhost:41761/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41761/tests/dromaeo/cssquery-ext.html) 19:37:38 INFO - PROCESS | 26222 | RSS: Main: 261963776 19:37:38 INFO - PROCESS | 26222 | 19:40:40 INFO - PROCESS | 26222 | Cycle 1(1): loaded http://localhost:41761/tests/dromaeo/cssquery-ext.html (next: http://localhost:41761/tests/dromaeo/cssquery-jquery.html) 19:40:41 INFO - PROCESS | 26222 | RSS: Main: 229888000 19:40:41 INFO - PROCESS | 26222 | 19:43:43 INFO - PROCESS | 26222 | Cycle 1(1): loaded http://localhost:41761/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41761/tests/dromaeo/cssquery-mootools.html) 19:43:43 INFO - PROCESS | 26222 | RSS: Main: 178245632 19:43:43 INFO - PROCESS | 26222 | 19:46:45 INFO - PROCESS | 26222 | Cycle 1(1): loaded http://localhost:41761/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41761/tests/dromaeo/cssquery-prototype.html) 19:46:46 INFO - PROCESS | 26222 | RSS: Main: 176852992 19:46:46 INFO - PROCESS | 26222 | 19:49:48 INFO - PROCESS | 26222 | Cycle 1(1): loaded http://localhost:41761/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41761/tests/dromaeo/cssquery-yui.html) 19:49:49 INFO - PROCESS | 26222 | RSS: Main: 178475008 19:49:49 INFO - PROCESS | 26222 | 19:52:51 INFO - PROCESS | 26222 | Cycle 1(1): loaded http://localhost:41761/tests/dromaeo/cssquery-yui.html (next: http://localhost:41761/tests/dromaeo/cssquery-dojo.html) 19:52:52 INFO - PROCESS | 26222 | RSS: Main: 263131136 19:52:52 INFO - PROCESS | 26222 | 19:52:52 INFO - PROCESS | 26222 | __start_tp_report 19:52:52 INFO - PROCESS | 26222 | _x_x_mozilla_page_load 19:52:52 INFO - PROCESS | 26222 | _x_x_mozilla_page_load_details 19:52:52 INFO - PROCESS | 26222 | |i|pagename|runs| 19:52:52 INFO - PROCESS | 26222 | |0;dojo.html;3268.395346860831;3282.885099021534;3294.55523504327;3318.518814771857;3342.799432034078;5662.405447428017;5694.145878118285;5732.5987180897355;5755.223044888939;5768.798092066779;5671.461211184931;5718.971405142979;5730.3123625164935;5736.368999410072;5765.509931655814;5158.329417175772;5210.6352555320955;5210.791568337262;5236.816711415101;5262.210668399733;5052.444231134572;5094.057599344114;5265.7893684252585;5272.947270527303;5307.787688492455;5140.100482415581;5145.76844042019;5204.609654275944;5259.631825772198;5275.920861187085;5260.158374660055;5290.576753859682;5295.7881684732565;5297.4437684043005;5298.178782288752;5019.673721208146;5106.64253502255;5172.965406918632;5227.843164705065;5230.110881150214;5582.692951887645;5597.216389705444;5671.00757367459;5761.827145185651;5773.2494775679315;6077.513798896078;6112.572119951606;6127.662978536178;6128.3258841527395;6130.356312587159;6169.7532098716;6175.907361389583;6190.226221722285;6194.721237544276;6194.876102477971;5575.135853942646;5594.944050559483;5641.71791410432;5661.660300381949;5740.483356497852;6205.875882482415;6216.378362163748;6216.595921265103;6218.595791273553;6221.47117495018;5922.378150294243;5971.164037034819;5980.55145864062;5981.700914954322;5985.431384018511;5944.613600115978;6009.849753756191;6061.6666083365835;6079.209702738615;6131.315499379051;6038.547108966757;6050.878982420327;6083.11794789753;6100.145979562864;6106.72519736613;6078.422549857756;6087.939120608826;6096.908546371808;6098.207233059763;6108.908366374508;4572.611328037087;4580.908381832412;4609.3086037094035;4616.238320677118;4681.836135735233;4146.212219678251;4146.4402305688345;4158.7296825706235;4159.9792001039805;4172.4993000840095;5739.971300143556;5765.3369862465515;5766.394528574523;5769.278840144982;5797.652140871562;5921.496672782861;6032.065029920369;6083.087536869417;6099.512039036867;6114.905704492704;5826.329972053183;5835.5915085944425;5839.182514447981;5840.474357307863;5868.207791948118;5148.922766158511;5183.948160518422;5194.714290713971;5200.089984252741;5234.555062819703;6083.7566497340495;6213.813585592439;6282.623042617458;6283.183186185766;6380.585261958051;6112.205413296421;6113.572049956639;6117.388261173847;6117.571769976059;6117.877642447215;6358.745650173942;6363.109164717036;6364.109024736448;6369.363063693593;6375.553711240169;5830.679312637676;5842.211301474416;5861.706914654335;5870.5010074144175;6008.128821320972;6032.728527216198;6039.184710064085;6043.456088952015;6045.335013148462;6046.758129674764;5863.061910094364;5899.174115623902;5914.4381283777975;5921.792737254175;5924.200232968667;5715.771369145187;5813.215215945793;5856.326522449803;5885.470307672159;5976.521878249642;5886;5886.6173698709445;5890.646561206341;5892.263467066617;5893.263342082239;5940.702964851826;5969.074793407029;5983.37174596661;5985.341612422717;5988.850278743066;5660.858478537904;5722.513586345207;5840.240768700041;5842.649441033552;5902.881942361043 19:52:52 INFO - PROCESS | 26222 | |1;ext.html;581.2037508613198;605.9242594675668;608.9573729838913;620.1534904854873;651.8272657745696;35426.82286588567;46845.29732054018;48006.27990580144;48067;48082.27876581854;50799;51070.489295107036;51083.74458127713;51242;51332.74333628336;8585.656573737075;9323.580438880233;9488.857667134998;9492.81014379712;10667.573297068107;10813.08088812452;11233.943830280878;11279.887201128007;11281.435928203598;11291.040261577777;11399.316041037522;11455.14086443516;11461.025812805921;11462.656120316404;11463.140264480156;11320.547178112865;11341.206115571871;11352.886471135265;11354.943225283903;11357.204995650307;11097.778044439066;11221.214514983954;11253.0997520199;11256.493457794419;11263.66209013731;46719.76640116812;47146.5285347149;47172.764136179445;47181;47224;3843.2313537292375;4105.753654780723;4165.354370072643;4299.892502687427;4534.863954081383;34741.47887781685;35427.11432214215;35493.46759798605;35523.82238088793;35904.461433078526;4666.556677115668;4669.486356500782;4683.812647494096;4710.882227944295;4885;354853;356029.9999999974;356477;358341;359315;65462.34537654562;65787.34212657908;65808.34191658118;65826;65834;40843.1831363377;41773;41798;43839.78080109643;70143.64928175429;18181.363652272372;18297.268109275745;18306.359277425225;18311.267549297878;18377.356792512197;13716.519921802688;13895;14061.78907316391;14199.574012779633;14247.928760356132;21698.56602867934;21714.782852171593;21737.45656358572;21740.782592174193;21887.343379698636;7170.282971702788;7214.49498535108;7324.2309557496765;7424.96287518559;7496.7376141834775;1060.4697651174413;1135.9488823002985;1138.9145814063813;1152.4353066997278;1203.3141109567462;36140;36174;36553.45169822455;36636.633633663856;36658.45012324817;1179.3572502985721;1183.8520184976878;1210.4552951172009;1227.6501197158946;1267.33464930912;17345.826541734674;17467.388641397745;17549.29802807899;17774.555636108944;17839;155158.22420887824;155279;155321;155635;156745.21627392017;67215.32784672189;67411.66294168597;67639.32360676429;67686.32313676899;67807;1334.292824802856;1423.9992810101824;1455.9417623295162;1468.383279022813;1482.3403585404392;35896.64103359037;36324;37394.62605373911;37408.251834963696;37472.81263593664;57099.14351284651;57708;57792.422075778704;57799.4220057794;57803;470885;472306;472920;474028;474859;28144.607708345022;31212.843935780176;31414.68585314209;31510.68489315169;32739.508907366886;48305.75847120742;54126;54270.45729542654;54965.72517137389;55313.72343138258;44226;44827.551724482335;45473.772631136635;45590.82980313979;46582.30126548172;44930.77534612306;46018;46220;47380.763096184295;47479.762601186776;44419.11161776811;44522;44662;44770.3284450727;44994.77502612466;37740.81129594444;39973;40262;40884.386734198415;41135;30014.549781752856;30120.84939575288;30721.69278307188;30739.231519212197;33501.77765349715 19:52:52 INFO - PROCESS | 26222 | |2;jquery.html;392.44860970336686;395.427742534811;426.2540554030447;429.120303378075;434.43740356238686;19084.71372929407;20502.794972050277;20724.274650387222;21160.365189044314;21369.57260854782;18882.24471021161;19216.9039154804;19356;19545.51136221595;21214.151433942625;5699.629524080932;5921.378255283176;5926.762929482816;5929.28848538176;5930.229070220866;5790.449907258803;5838.9124163137585;5839.270091238595;5874.706264686749;5875.500582450497;5724.856878578027;5837.678927658977;5874.3244526879525;5881.617694849842;5883.588148829584;5796.65220086794;5801.07182850744;5831.88336233273;5846.795362162304;5868.530517558585;5214.191800270964;5262.368515778093;5268.262443257947;5326.733663316857;5329.094054010828;22865.77134228653;23131.421714457105;23323.76676233233;23470.882645586833;23630.40923976897;2531.4683916377585;2539.390546268882;2539.606361014046;2576.6135079738;2745.917622471309;15201.08793472384;15300.311485983157;15393.769093463607;15529.844701552953;15806.762898556532;1994.7606287245478;2091.4248581640018;2122.2466024561368;2145.495808485005;2210.602091623507;61072.69463652653;65080.674596627185;77629.61185194095;83744;87953;11333;11345.262557933707;11526.250793698382;11589.884101159048;11799.410029498491;13743.587692369045;14186.503472378417;14576.271186440848;14609.488667896572;15323.846761532464;7985.640646170937;8074.636641351153;8345.499270043816;8487.278581320534;8495.065542790288;8069.273765361146;8070.515769053875;8073.7577872663915;8261.934516370908;8287.62705678246;6352.618842869442;6438.581492202992;6539.215294164731;6566.217287866848;6569.80290591283;1903.7144428335585;1981.890995995234;1999.9500012499514;2009.65835807913;2022.3427386099577;1047.36634336227;1068.572570971602;1091.028984204059;1116.4473585575192;1135.6706555098929;20683.276085337242;21398.144074237167;21468.24861129884;21487;21589.352319430443;1062.8512008318842;1066.36018388966;1067.412922892406;1069.417167643636;1123.9943800280946;10723.571057157626;11877;12132.090093243041;12193.573224937261;12213.389330533439;547753;561913;600174;617866;623986;95482;101174;102246.48876755718;102914;103966.4801676002;1004.5128112865254;1037.7301901505512;1065.5524679634418;1076.5048077884105;1077.563586747353;18289.26842926268;18445.169967351234;18683.1592578337;18799.342023029127;19140.617187656448;44554.3316850254;44857.55142448534;45132.32301515545;45395.54604453913;45699.08601828011;60162.39837601743;62571;66371.33628663652;67498;67658.66170669116;7990.560519171501;8140.715074972348;8386.622601983048;8793.164649358301;9105.453672779655;13732.519361822287;14312.57062288133;14369.425222990963;14379.424823006962;14524.419023239374;11998.340091294713;12407.875921240671;12586.307753073668;12689.873101269239;12807.61577152687;12063.517459301529;12353.073519486254;12574.811377829157;12699.111062225555;12712.364381781059;12173.147879648339;12288.1398302118;12580.119391642496;12603;12875.484980600671;8298.556099218757;8449.45078569891;8527.872081918653;8551.657933682583;8706.347023973101;10747.623833165802;12294.323812190416;12484.625461236177;12561.685957851127;13068.5426010093 19:52:52 INFO - PROCESS | 26222 | |3;mootools.html;1289.1942535915052;1482.599698081518;1557.5483109898128;1671.264643556835;1789.8568114550828;9233.861492077616;9699.806003879918;9749.122578967883;10034.247431442653;10050.49747512625;9941.254405919548;10063.446510441923;10081.09270165685;10085.092341689247;10086.949565252164;2554.0549996501272;2589.4562141950214;2603.635491031257;2603.8437693738438;2604.440045390241;2504.7370026147264;2588.4693637804203;2590.7927365810688;2592.481503699258;2593.1313010141625;2557.4629327841085;2559.872006399682;2602.687677478705;2603.5313643544155;2609.23027706826;2446.987765061172;2453.815963802722;2463.1132792194708;2499.4251322195996;2502.73721259267;2437.9634305485433;2444.4622183119686;2444.792192663626;2446.1193970170725;2484.9751502484924;9860.90139098607;9935.05616966387;10006.399616023062;10039.04629060238;10044.598216071421;5289.338832645919;5480.808171713971;5491;5491.2312276281355;5497.615166938316;3565.144365352323;3578.7474383965664;3606.1511405989236;3612.277544491086;3700.5004324416127;1188.6315242274923;1199.7420554580808;1204.3737256626594;1221.2916508425092;1243.3658833994728;13912.721745565032;14011.299435028208;14045.157290562598;14059.929700351433;14136.575902722934;4840.467548569654;4864.416270047617;4887.584555312838;4890.633202509826;5080.49195080489;10877.0754485868;10886.510107045202;10932.890671093346;10950;10972.835407468894;5522.282103326622;5528.143137813645;5533.889322213614;5535.7785688572;5536.280283563111;4673.275642275453;4751.192297309466;5112.233165025276;5205.9219111713355;5246.554042906319;4255.340422234559;4284.89287767802;4300.505441874162;4332.653387728974;4335.241332766753;1250.2186133666457;1257.666718319646;1263.6714454241962;1283.146707439561;1287.1697754948007;967.7822489939848;1034.1158309645296;1059.0362769879373;1067.1729409707539;1076.2358725304964;6628.602283862983;6770.059342741675;6920.757773477904;6944.895826562605;6950.617716025566;474.4235753559442;478.1751478699216;481.6002717744334;493.78520343650627;505.4793562630496;4659.091477161988;4701.576858082789;4794.760261986957;4849.951500485021;4854.538818812207;11746;12322.322272274932;12682.429290681941;12790.424430900632;12865.099443038898;12069.698257543456;12430;12456.128071035122;12461.252324860538;12471.875281247252;9141.862872056925;9318.72043838686;9326.860097098548;9328.999999999864;9335.579898904565;6706.899396509154;6815.931840681728;6902;7001.649917504206;7009.264027277062;11449.771004579696;11531.077513798708;11659.650210493699;11692.766144676889;11793.11551633648;10554.050135487843;11132.276402033842;11622.244554104294;11639.301641901513;11815.291082535075;8502.277306429023;8850.159234872677;8934.955325223333;8944.68693595721;9019.052999435227;9224.815503690023;9377.249820014518;9383.953080234554;9389.812203756022;9403.576839042396;8061.596920154087;8066.274035336848;8171.387145964195;8734.388592798443;9021;8585.18440748128;8687.174718401742;8708.134446602166;8716.128387161232;8996.910030899608;7462;7616.657250423634;7624.351930086005;7707.421943354158;7850.411219158472;5767.7404516796005;5845.590808643354;5887.61730487517;5950.672713000827;5961.612495187799;5865.765369385347;5941.851453713692;5952.76188952437;5975.521958243417;5998.49012833914 19:52:52 INFO - PROCESS | 26222 | |4;prototype.html;351.31493587504366;353.589835790483;361.9221867298532;363.96542328478785;369.10307951677413;5497.587680923927;5526.861828454285;5528.9723551382285;5563.972180139093;5582.692951887645;5467.6719396836215;5487.938925195356;5499.477549632788;5501.367342755584;5502.504774570288;4254.659627229822;4328.696991210617;4332.700189943021;4408.448943882015;4413.161499315136;4345.283028300327;4352.630026447756;4389.4952080510675;4397.801099450275;4417.845375411861;4352.912941741163;4358.692392282319;4368.541303163154;4369.956300437003;4376.212281789277;4169.8741339838225;4343.109662519176;4357.324614684729;4366.061296821167;4392.407025051609;3818.522684664417;3829.3107240696663;3841;3848.4034974578985;3869.361555343365;5344.5724342052545;5355.866103347408;5357.276767636358;5366.168243922199;5410.701431656414;4843.636727245435;4916.803327866881;4963.553280204799;4977.377827771528;4978.327925730016;5721.995952125126;5729.856753581152;5733.283339582552;5735.283089613798;5786.363500014995;4214.599613036788;4239.957600424018;4244.61798438139;4245.6815738819405;4247.554006829269;5646.604737668365;5787.797427090031;5814.738336774855;6001.459868611802;6042.184305118797;4236.597523235349;4239.618434340924;4259.403683484315;4289.270823959934;4291.356296555542;4324.113556720865;4337.9783101084295;4342.32693932441;4346.717463364871;4351.673624478177;4741.620670346433;4819.542143496362;4904.705717656952;4913.263010548446;4938.506149385033;5705.258316418922;5710.7430165642645;5713.771449141987;5723.594461295523;5748.798792042258;1479.408236705305;1489.4489039055618;1490.4038384646228;1500.7298686236581;1503.6541595432893;933.2067742419025;964.9710508684751;972.7033254857195;974.340051658975;981.5582987655583;4942.2092465206115;4955.515258690891;4958.256261560795;4966.205407134913;4969.627277954168;5160.561352285098;5164.715940623227;5204.76578553966;5208.609354298443;5242.2700508831995;4628.421447319085;4846.466888642247;4948.911239527299;4971.229459433794;5000.724960127228;2722.006467639297;2725.2369336585393;2729.7952653551065;2736.945261094767;2760.917172484829;5296.629235953524;5311.380029091087;5327.280817089721;5333.066713325153;5353.598480114007;5219.677243626626;5268.525832675077;5596.664200148004;6005.309389420186;6074.453299203093;6206.472449841724;6211.16149319845;6234.407731265523;6243.937560624427;6255.999040153553;5640.520555752807;5971.880562388641;5977.491913187254;5977.760889564369;6016.398360163948;5834.18321435008;5847.239858818326;5849.736761845812;5851.561132914963;5869.0338736523745;5917.733701983335;6021.7956408717555;6047.516198704181;6057.121717351048;6191.752329906753;4142.399352093989;4234.491860976703;4271.359296105485;4285.7642829644665;4301.91396172081;5794.710264486843;5977.551683623658;5982.282126144891;6007.219061521974;6007.939920600738;5703.258576385043;5752.309722833287;5757.481826635623;5779.219805326227;5787.94212057874;5764.279465066867;5782.2483077199695;5786.276715410574;5813.360530341574;5825.883482330414;5523.502884740393;5749.655020698771;5767.884642307215;5788.82633520995;5818.0981947798155;5818.854528636818;5826.621269617462;5840.2115714378015;5914.467697907209;5938.8218353449465;5489.780408783604;5685.175649530712;5695.971520142372;5745.626534275259;5760.971195143998 19:52:52 INFO - PROCESS | 26222 | |5;yui.html;791.9089304729956;807.2613558593887;830.2195935820329;857.8713193021046;869.1091631078148;15320.616984575378;16306.592335191612;16347.182640867939;16376.75434868475;16417.75373369399;16474.09392483413;16551;16588.253528591213;16638.584035399123;16639.33442662295;734.5299008634478;863.0334025891011;881.4579033894157;886.57887503436;892.6072528087653;590.9645421274737;592.4904582059426;593.2199158107097;598.5062323583039;600.1837500998638;936.7704912296497;940.6848705683605;940.9012053734363;941.5339406993547;942.114412452296;928.679605536089;933.8039011807494;935.6304259817368;938.5776400619749;939.3518472254191;687.6939761805999;690.4752388185016;691.1774987764544;692.1037256752468;692.1728534401392;16707.749383759252;16707.916460417742;16712.66574668512;16715.247813848408;16752.078635675032;12798.48806047757;13345.733085338336;13891.374888130058;14520.564383068524;15058;13772.586822395344;13966.301684915714;14059.312882454069;14160.291985400792;14387.208703521303;4844.491328410501;4881.389826271716;4900.58345040672;5003.799848006075;5008.248762685626;88588.55705721494;89154.55422722909;90301;90447.54776226143;95108.9999999993;10909;11668.824967625493;11700.122490813223;11884.573851137919;11935.343556104497;20530.178792848117;20665.070071846803;20700.689489657667;20768.065437055368;20784.58430831405;8651.52416617092;8871.955640221757;8888.911110888937;8902.332325075646;8928.15182557656;4852.805887764521;4986.650934434628;4989.800407983713;5000.524950129732;5273.314469119066;3821.3694740367528;3901.8634347797692;3990.2418540477215;3999.0202400412086;4007.198560287954;660.0989649128904;661.153723234261;669.8760729265101;672.8082496488478;679.6907407129744;603.5774957529746;619.1548536248079;635.0537698828712;657.0341597851151;679.67035987546;8526.488410695378;8762.737117886474;8868.778280543038;8906.55467226636;9075.773105672279;635.1140159477503;664.3887623386496;666.1140682891743;667.4660271782651;683.852971611106;4303.1608836277255;4366.43236379275;4440.645603091024;4500.504944456107;4520.864374068783;132881;133152;133739;136407.31796340956;136664;90981.54509227411;94274;113002;115712.42143789228;115865.42067289779;57447.712761436764;80892;85588.57205714055;91953;92128;8755;8807.691730789391;8954.552272386485;9082.54587270647;9234.261259099121;49005.50994490009;50456.24315635342;52524.73737631287;54699;56026.71986640041;53586.73206633942;69492;81821.59089204516;82090;84050.21774020306;12995.415206315549;13033;13109.475620975054;13111.344432778515;13127.409266583218;12896.871031289567;13148.474061037452;13325.13386629866;13380.598582042554;13508.189508629514;12224.403842983216;12386.75226495483;12649.87350126487;12732.490700372251;13137;13255;13259.270740109387;13384.665383365495;13418.731625367633;13594.86405135936;12665.176763510342;13082.215067096005;13513.527026554024;13649;13956.162630242217;10489.527971241465;10899;11020.063294620048;11380;11563.942180289045;9070.637174512945;9333.206677432494;9490.620375184915;10363.63727269542;10421.895781042092 19:52:52 INFO - PROCESS | 26222 | __end_tp_report 19:52:52 INFO - PROCESS | 26222 | __start_cc_report 19:52:52 INFO - PROCESS | 26222 | _x_x_mozilla_cycle_collect,997 19:52:52 INFO - PROCESS | 26222 | __end_cc_report 19:52:52 INFO - PROCESS | 26222 | __startTimestamp1477363972198__endTimestamp 19:52:52 INFO - PROCESS | 26222 | 19:52:52 INFO - PROCESS | 26222 | ------- Summary: start ------- 19:52:52 INFO - PROCESS | 26222 | Number of tests: 6 19:52:52 INFO - PROCESS | 26222 | 19:52:52 INFO - PROCESS | 26222 | [#0] dojo.html Cycles:165 Average:5651.97 Median:5856.33 stddev:637.84 (10.9%) stddev-sans-first:611.77 19:52:52 INFO - PROCESS | 26222 | Values: 3268.4 3282.9 3294.6 3318.5 3342.8 5662.4 5694.1 5732.6 5755.2 5768.8 5671.5 5719.0 5730.3 5736.4 5765.5 5158.3 5210.6 5210.8 5236.8 5262.2 5052.4 5094.1 5265.8 5272.9 5307.8 5140.1 5145.8 5204.6 5259.6 5275.9 5260.2 5290.6 5295.8 5297.4 5298.2 5019.7 5106.6 5173.0 5227.8 5230.1 5582.7 5597.2 5671.0 5761.8 5773.2 6077.5 6112.6 6127.7 6128.3 6130.4 6169.8 6175.9 6190.2 6194.7 6194.9 5575.1 5594.9 5641.7 5661.7 5740.5 6205.9 6216.4 6216.6 6218.6 6221.5 5922.4 5971.2 5980.6 5981.7 5985.4 5944.6 6009.8 6061.7 6079.2 6131.3 6038.5 6050.9 6083.1 6100.1 6106.7 6078.4 6087.9 6096.9 6098.2 6108.9 4572.6 4580.9 4609.3 4616.2 4681.8 4146.2 4146.4 4158.7 4160.0 4172.5 5740.0 5765.3 5766.4 5769.3 5797.7 5921.5 6032.1 6083.1 6099.5 6114.9 5826.3 5835.6 5839.2 5840.5 5868.2 5148.9 5183.9 5194.7 5200.1 5234.6 6083.8 6213.8 6282.6 6283.2 6380.6 6112.2 6113.6 6117.4 6117.6 6117.9 6358.7 6363.1 6364.1 6369.4 6375.6 5830.7 5842.2 5861.7 5870.5 6008.1 6032.7 6039.2 6043.5 6045.3 6046.8 5863.1 5899.2 5914.4 5921.8 5924.2 5715.8 5813.2 5856.3 5885.5 5976.5 5886.0 5886.6 5890.6 5892.3 5893.3 5940.7 5969.1 5983.4 5985.3 5988.9 5660.9 5722.5 5840.2 5842.6 5902.9 19:52:52 INFO - PROCESS | 26222 | 19:52:52 INFO - PROCESS | 26222 | [#1] ext.html Cycles:180 Average:53203.50 Median:37440.53 stddev:93588.84 (250.0%) stddev-sans-first:93767.98 19:52:52 INFO - PROCESS | 26222 | Values: 581.2 605.9 609.0 620.2 651.8 35426.8 46845.3 48006.3 48067.0 48082.3 50799.0 51070.5 51083.7 51242.0 51332.7 8585.7 9323.6 9488.9 9492.8 10667.6 10813.1 11233.9 11279.9 11281.4 11291.0 11399.3 11455.1 11461.0 11462.7 11463.1 11320.5 11341.2 11352.9 11354.9 11357.2 11097.8 11221.2 11253.1 11256.5 11263.7 46719.8 47146.5 47172.8 47181.0 47224.0 3843.2 4105.8 4165.4 4299.9 4534.9 34741.5 35427.1 35493.5 35523.8 35904.5 4666.6 4669.5 4683.8 4710.9 4885.0 354853.0 356030.0 356477.0 358341.0 359315.0 65462.3 65787.3 65808.3 65826.0 65834.0 40843.2 41773.0 41798.0 43839.8 70143.6 18181.4 18297.3 18306.4 18311.3 18377.4 13716.5 13895.0 14061.8 14199.6 14247.9 21698.6 21714.8 21737.5 21740.8 21887.3 7170.3 7214.5 7324.2 7425.0 7496.7 1060.5 1135.9 1138.9 1152.4 1203.3 36140.0 36174.0 36553.5 36636.6 36658.5 1179.4 1183.9 1210.5 1227.7 1267.3 17345.8 17467.4 17549.3 17774.6 17839.0 155158.2 155279.0 155321.0 155635.0 156745.2 67215.3 67411.7 67639.3 67686.3 67807.0 1334.3 1424.0 1455.9 1468.4 1482.3 35896.6 36324.0 37394.6 37408.3 37472.8 57099.1 57708.0 57792.4 57799.4 57803.0 470885.0 472306.0 472920.0 474028.0 474859.0 28144.6 31212.8 31414.7 31510.7 32739.5 48305.8 54126.0 54270.5 54965.7 55313.7 44226.0 44827.6 45473.8 45590.8 46582.3 44930.8 46018.0 46220.0 47380.8 47479.8 44419.1 44522.0 44662.0 44770.3 44994.8 37740.8 39973.0 40262.0 40884.4 41135.0 30014.5 30120.8 30721.7 30739.2 33501.8 19:52:52 INFO - PROCESS | 26222 | 19:52:52 INFO - PROCESS | 26222 | [#2] jquery.html Cycles:180 Average:32673.09 Median:21477.62 stddev:97085.49 (452.0%) stddev-sans-first:97327.58 19:52:52 INFO - PROCESS | 26222 | Values: 392.4 395.4 426.3 429.1 434.4 19084.7 20502.8 20724.3 21160.4 21369.6 18882.2 19216.9 19356.0 19545.5 21214.2 5699.6 5921.4 5926.8 5929.3 5930.2 5790.4 5838.9 5839.3 5874.7 5875.5 5724.9 5837.7 5874.3 5881.6 5883.6 5796.7 5801.1 5831.9 5846.8 5868.5 5214.2 5262.4 5268.3 5326.7 5329.1 22865.8 23131.4 23323.8 23470.9 23630.4 2531.5 2539.4 2539.6 2576.6 2745.9 15201.1 15300.3 15393.8 15529.8 15806.8 1994.8 2091.4 2122.2 2145.5 2210.6 61072.7 65080.7 77629.6 83744.0 87953.0 11333.0 11345.3 11526.3 11589.9 11799.4 13743.6 14186.5 14576.3 14609.5 15323.8 7985.6 8074.6 8345.5 8487.3 8495.1 8069.3 8070.5 8073.8 8261.9 8287.6 6352.6 6438.6 6539.2 6566.2 6569.8 1903.7 1981.9 2000.0 2009.7 2022.3 1047.4 1068.6 1091.0 1116.4 1135.7 20683.3 21398.1 21468.2 21487.0 21589.4 1062.9 1066.4 1067.4 1069.4 1124.0 10723.6 11877.0 12132.1 12193.6 12213.4 547753.0 561913.0 600174.0 617866.0 623986.0 95482.0 101174.0 102246.5 102914.0 103966.5 1004.5 1037.7 1065.6 1076.5 1077.6 18289.3 18445.2 18683.2 18799.3 19140.6 44554.3 44857.6 45132.3 45395.5 45699.1 60162.4 62571.0 66371.3 67498.0 67658.7 7990.6 8140.7 8386.6 8793.2 9105.5 13732.5 14312.6 14369.4 14379.4 14524.4 11998.3 12407.9 12586.3 12689.9 12807.6 12063.5 12353.1 12574.8 12699.1 12712.4 12173.1 12288.1 12580.1 12603.0 12875.5 8298.6 8449.5 8527.9 8551.7 8706.3 10747.6 12294.3 12484.6 12561.7 13068.5 19:52:52 INFO - PROCESS | 26222 | 19:52:52 INFO - PROCESS | 26222 | [#3] mootools.html Cycles:180 Average:6451.51 Median:4316.58 stddev:3782.82 (87.6%) stddev-sans-first:3773.54 19:52:52 INFO - PROCESS | 26222 | Values: 1289.2 1482.6 1557.5 1671.3 1789.9 9233.9 9699.8 9749.1 10034.2 10050.5 9941.3 10063.4 10081.1 10085.1 10086.9 2554.1 2589.5 2603.6 2603.8 2604.4 2504.7 2588.5 2590.8 2592.5 2593.1 2557.5 2559.9 2602.7 2603.5 2609.2 2447.0 2453.8 2463.1 2499.4 2502.7 2438.0 2444.5 2444.8 2446.1 2485.0 9860.9 9935.1 10006.4 10039.0 10044.6 5289.3 5480.8 5491.0 5491.2 5497.6 3565.1 3578.7 3606.2 3612.3 3700.5 1188.6 1199.7 1204.4 1221.3 1243.4 13912.7 14011.3 14045.2 14059.9 14136.6 4840.5 4864.4 4887.6 4890.6 5080.5 10877.1 10886.5 10932.9 10950.0 10972.8 5522.3 5528.1 5533.9 5535.8 5536.3 4673.3 4751.2 5112.2 5205.9 5246.6 4255.3 4284.9 4300.5 4332.7 4335.2 1250.2 1257.7 1263.7 1283.1 1287.2 967.8 1034.1 1059.0 1067.2 1076.2 6628.6 6770.1 6920.8 6944.9 6950.6 474.4 478.2 481.6 493.8 505.5 4659.1 4701.6 4794.8 4850.0 4854.5 11746.0 12322.3 12682.4 12790.4 12865.1 12069.7 12430.0 12456.1 12461.3 12471.9 9141.9 9318.7 9326.9 9329.0 9335.6 6706.9 6815.9 6902.0 7001.6 7009.3 11449.8 11531.1 11659.7 11692.8 11793.1 10554.1 11132.3 11622.2 11639.3 11815.3 8502.3 8850.2 8935.0 8944.7 9019.1 9224.8 9377.2 9384.0 9389.8 9403.6 8061.6 8066.3 8171.4 8734.4 9021.0 8585.2 8687.2 8708.1 8716.1 8996.9 7462.0 7616.7 7624.4 7707.4 7850.4 5767.7 5845.6 5887.6 5950.7 5961.6 5865.8 5941.9 5952.8 5975.5 5998.5 19:52:52 INFO - PROCESS | 26222 | 19:52:52 INFO - PROCESS | 26222 | [#4] prototype.html Cycles:180 Average:4784.29 Median:5338.82 stddev:1398.69 (26.2%) stddev-sans-first:1362.46 19:52:52 INFO - PROCESS | 26222 | Values: 351.3 353.6 361.9 364.0 369.1 5497.6 5526.9 5529.0 5564.0 5582.7 5467.7 5487.9 5499.5 5501.4 5502.5 4254.7 4328.7 4332.7 4408.4 4413.2 4345.3 4352.6 4389.5 4397.8 4417.8 4352.9 4358.7 4368.5 4370.0 4376.2 4169.9 4343.1 4357.3 4366.1 4392.4 3818.5 3829.3 3841.0 3848.4 3869.4 5344.6 5355.9 5357.3 5366.2 5410.7 4843.6 4916.8 4963.6 4977.4 4978.3 5722.0 5729.9 5733.3 5735.3 5786.4 4214.6 4240.0 4244.6 4245.7 4247.6 5646.6 5787.8 5814.7 6001.5 6042.2 4236.6 4239.6 4259.4 4289.3 4291.4 4324.1 4338.0 4342.3 4346.7 4351.7 4741.6 4819.5 4904.7 4913.3 4938.5 5705.3 5710.7 5713.8 5723.6 5748.8 1479.4 1489.4 1490.4 1500.7 1503.7 933.2 965.0 972.7 974.3 981.6 4942.2 4955.5 4958.3 4966.2 4969.6 5160.6 5164.7 5204.8 5208.6 5242.3 4628.4 4846.5 4948.9 4971.2 5000.7 2722.0 2725.2 2729.8 2736.9 2760.9 5296.6 5311.4 5327.3 5333.1 5353.6 5219.7 5268.5 5596.7 6005.3 6074.5 6206.5 6211.2 6234.4 6243.9 6256.0 5640.5 5971.9 5977.5 5977.8 6016.4 5834.2 5847.2 5849.7 5851.6 5869.0 5917.7 6021.8 6047.5 6057.1 6191.8 4142.4 4234.5 4271.4 4285.8 4301.9 5794.7 5977.6 5982.3 6007.2 6007.9 5703.3 5752.3 5757.5 5779.2 5787.9 5764.3 5782.2 5786.3 5813.4 5825.9 5523.5 5749.7 5767.9 5788.8 5818.1 5818.9 5826.6 5840.2 5914.5 5938.8 5489.8 5685.2 5696.0 5745.6 5761.0 19:52:52 INFO - PROCESS | 26222 | 19:52:52 INFO - PROCESS | 26222 | [#5] yui.html Cycles:180 Average:21879.27 Median:4988.23 stddev:32952.00 (660.6%) stddev-sans-first:33006.40 19:52:52 INFO - PROCESS | 26222 | Values: 791.9 807.3 830.2 857.9 869.1 15320.6 16306.6 16347.2 16376.8 16417.8 16474.1 16551.0 16588.3 16638.6 16639.3 734.5 863.0 881.5 886.6 892.6 591.0 592.5 593.2 598.5 600.2 936.8 940.7 940.9 941.5 942.1 928.7 933.8 935.6 938.6 939.4 687.7 690.5 691.2 692.1 692.2 16707.7 16707.9 16712.7 16715.2 16752.1 12798.5 13345.7 13891.4 14520.6 15058.0 13772.6 13966.3 14059.3 14160.3 14387.2 4844.5 4881.4 4900.6 5003.8 5008.2 88588.6 89154.6 90301.0 90447.5 95109.0 10909.0 11668.8 11700.1 11884.6 11935.3 20530.2 20665.1 20700.7 20768.1 20784.6 8651.5 8872.0 8888.9 8902.3 8928.2 4852.8 4986.7 4989.8 5000.5 5273.3 3821.4 3901.9 3990.2 3999.0 4007.2 660.1 661.2 669.9 672.8 679.7 603.6 619.2 635.1 657.0 679.7 8526.5 8762.7 8868.8 8906.6 9075.8 635.1 664.4 666.1 667.5 683.9 4303.2 4366.4 4440.6 4500.5 4520.9 132881.0 133152.0 133739.0 136407.3 136664.0 90981.5 94274.0 113002.0 115712.4 115865.4 57447.7 80892.0 85588.6 91953.0 92128.0 8755.0 8807.7 8954.6 9082.5 9234.3 49005.5 50456.2 52524.7 54699.0 56026.7 53586.7 69492.0 81821.6 82090.0 84050.2 12995.4 13033.0 13109.5 13111.3 13127.4 12896.9 13148.5 13325.1 13380.6 13508.2 12224.4 12386.8 12649.9 12732.5 13137.0 13255.0 13259.3 13384.7 13418.7 13594.9 12665.2 13082.2 13513.5 13649.0 13956.2 10489.5 10899.0 11020.1 11380.0 11563.9 9070.6 9333.2 9490.6 10363.6 10421.9 19:52:52 INFO - PROCESS | 26222 | -------- Summary: end -------- 19:52:52 INFO - PROCESS | 26222 | 19:52:52 INFO - TEST-INFO | 26222: exit 0 19:52:52 INFO - TEST-OK | dromaeo_css | took 1111927ms 19:52:52 INFO - TEST-START | kraken 19:52:52 INFO - operating with platform_type : linux_ 19:52:52 INFO - Initialising browser for kraken test... 19:52:52 INFO - TEST-INFO | started process 26415 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyciL8M/profile http://localhost:41761/getInfo.html) 19:52:56 INFO - PROCESS | 26415 | __metrics Screen width/height:1600/1200 19:52:56 INFO - PROCESS | 26415 | colorDepth:24 19:52:56 INFO - PROCESS | 26415 | Browser inner width/height: 1024/697 19:52:56 INFO - PROCESS | 26415 | __metrics 19:52:57 INFO - PROCESS | 26415 | 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" 19:52:57 INFO - PROCESS | 26415 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:52:58 INFO - TEST-INFO | 26415: exit 0 19:52:58 INFO - Browser initialized. 19:52:58 INFO - Running cycle 1/1 for kraken test... 19:52:58 INFO - TEST-INFO | started process 26501 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyciL8M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:53:09 INFO - PROCESS | 26501 | RSS: Main: 144187392 19:53:09 INFO - PROCESS | 26501 | 19:53:12 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41761/tests/kraken/driver.html?testName=audio-beat-detection) 19:53:13 INFO - PROCESS | 26501 | RSS: Main: 199065600 19:53:13 INFO - PROCESS | 26501 | 19:53:15 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41761/tests/kraken/driver.html?testName=audio-dft) 19:53:16 INFO - PROCESS | 26501 | RSS: Main: 184127488 19:53:16 INFO - PROCESS | 26501 | 19:53:19 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41761/tests/kraken/driver.html?testName=audio-fft) 19:53:19 INFO - PROCESS | 26501 | RSS: Main: 275230720 19:53:19 INFO - PROCESS | 26501 | 19:53:21 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41761/tests/kraken/driver.html?testName=audio-oscillator) 19:53:22 INFO - PROCESS | 26501 | RSS: Main: 180346880 19:53:22 INFO - PROCESS | 26501 | 19:53:24 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41761/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:53:25 INFO - PROCESS | 26501 | RSS: Main: 178839552 19:53:25 INFO - PROCESS | 26501 | 19:53:28 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41761/tests/kraken/driver.html?testName=imaging-darkroom) 19:53:28 INFO - PROCESS | 26501 | RSS: Main: 215097344 19:53:28 INFO - PROCESS | 26501 | 19:53:32 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41761/tests/kraken/driver.html?testName=imaging-desaturate) 19:53:32 INFO - PROCESS | 26501 | RSS: Main: 214261760 19:53:32 INFO - PROCESS | 26501 | 19:53:35 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41761/tests/kraken/driver.html?testName=json-parse-financial) 19:53:36 INFO - PROCESS | 26501 | RSS: Main: 214208512 19:53:36 INFO - PROCESS | 26501 | 19:53:37 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41761/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:53:38 INFO - PROCESS | 26501 | RSS: Main: 176214016 19:53:38 INFO - PROCESS | 26501 | 19:53:39 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41761/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:53:40 INFO - PROCESS | 26501 | RSS: Main: 184127488 19:53:40 INFO - PROCESS | 26501 | 19:53:42 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41761/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:53:42 INFO - PROCESS | 26501 | RSS: Main: 189640704 19:53:42 INFO - PROCESS | 26501 | 19:53:45 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41761/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:53:46 INFO - PROCESS | 26501 | RSS: Main: 192266240 19:53:46 INFO - PROCESS | 26501 | 19:53:48 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41761/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:53:49 INFO - PROCESS | 26501 | RSS: Main: 193507328 19:53:49 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | Cycle 1(1): loaded http://localhost:41761/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41761/tests/kraken/driver.html?testName=ai-astar) 19:53:51 INFO - PROCESS | 26501 | RSS: Main: 244125696 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | __start_tp_report 19:53:51 INFO - PROCESS | 26501 | _x_x_mozilla_page_load 19:53:51 INFO - PROCESS | 26501 | _x_x_mozilla_page_load_details 19:53:51 INFO - PROCESS | 26501 | |i|pagename|runs| 19:53:51 INFO - PROCESS | 26501 | |0;ai-astar;110;111;120;109;110;111;108;111;109;110 19:53:51 INFO - PROCESS | 26501 | |1;audio-beat-detection;143;117;145;177;116;157;144;115;174;117 19:53:51 INFO - PROCESS | 26501 | |2;audio-dft;161;161;161;159;161;160;162;161;160;162 19:53:51 INFO - PROCESS | 26501 | |3;audio-fft;76;76;75;180;75;74;76;75;175;76 19:53:51 INFO - PROCESS | 26501 | |4;audio-oscillator;83;83;153;84;84;83;152;84;83;143 19:53:51 INFO - PROCESS | 26501 | |5;imaging-gaussian-blur;116;116;117;116;117;116;117;116;117;116 19:53:51 INFO - PROCESS | 26501 | |6;imaging-darkroom;146;146;146;146;145;145;145;146;146;146 19:53:51 INFO - PROCESS | 26501 | |7;imaging-desaturate;95;95;96;95;95;95;96;95;95;94 19:53:51 INFO - PROCESS | 26501 | |8;json-parse-financial;53;51;50;49;50;53;50;51;50;51 19:53:51 INFO - PROCESS | 26501 | |9;json-stringify-tinderbox;59;62;61;60;60;61;60;60;60;60 19:53:51 INFO - PROCESS | 26501 | |10;stanford-crypto-aes;78;73;73;75;78;72;73;72;81;73 19:53:51 INFO - PROCESS | 26501 | |11;stanford-crypto-ccm;134;122;119;120;117;127;121;116;164;128 19:53:51 INFO - PROCESS | 26501 | |12;stanford-crypto-pbkdf2;154;155;156;153;153;154;152;158;153;151 19:53:51 INFO - PROCESS | 26501 | |13;stanford-crypto-sha256-iterative;61;60;64;61;64;65;62;65;60;61 19:53:51 INFO - PROCESS | 26501 | __end_tp_report 19:53:51 INFO - PROCESS | 26501 | __start_cc_report 19:53:51 INFO - PROCESS | 26501 | _x_x_mozilla_cycle_collect,1665 19:53:51 INFO - PROCESS | 26501 | __end_cc_report 19:53:51 INFO - PROCESS | 26501 | __startTimestamp1477364031269__endTimestamp 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | ------- Summary: start ------- 19:53:51 INFO - PROCESS | 26501 | Number of tests: 14 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#0] ai-astar Cycles:10 Average:110.90 Median:110.50 stddev:3.35 (3.0%) stddev-sans-first:3.54 19:53:51 INFO - PROCESS | 26501 | Values: 110.0 111.0 120.0 109.0 110.0 111.0 108.0 111.0 109.0 110.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:144.50 stddev:23.85 (16.5%) stddev-sans-first:25.28 19:53:51 INFO - PROCESS | 26501 | Values: 143.0 117.0 145.0 177.0 116.0 157.0 144.0 115.0 174.0 117.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 19:53:51 INFO - PROCESS | 26501 | Values: 161.0 161.0 161.0 159.0 161.0 160.0 162.0 161.0 160.0 162.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#3] audio-fft Cycles:10 Average:95.80 Median:75.50 stddev:43.08 (57.1%) stddev-sans-first:45.09 19:53:51 INFO - PROCESS | 26501 | Values: 76.0 76.0 75.0 180.0 75.0 74.0 76.0 75.0 175.0 76.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#4] audio-oscillator Cycles:10 Average:103.20 Median:83.00 stddev:31.94 (38.5%) stddev-sans-first:33.03 19:53:51 INFO - PROCESS | 26501 | Values: 83.0 83.0 153.0 84.0 84.0 83.0 152.0 84.0 83.0 143.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#5] imaging-gaussian-blur Cycles:10 Average:116.40 Median:116.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 19:53:51 INFO - PROCESS | 26501 | Values: 116.0 116.0 117.0 116.0 117.0 116.0 117.0 116.0 117.0 116.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 19:53:51 INFO - PROCESS | 26501 | Values: 146.0 146.0 146.0 146.0 145.0 145.0 145.0 146.0 146.0 146.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 19:53:51 INFO - PROCESS | 26501 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 94.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:1.32 (2.6%) stddev-sans-first:1.13 19:53:51 INFO - PROCESS | 26501 | Values: 53.0 51.0 50.0 49.0 50.0 53.0 50.0 51.0 50.0 51.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.82 (1.4%) stddev-sans-first:0.73 19:53:51 INFO - PROCESS | 26501 | Values: 59.0 62.0 61.0 60.0 60.0 61.0 60.0 60.0 60.0 60.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#10] stanford-crypto-aes Cycles:10 Average:74.80 Median:74.00 stddev:3.12 (4.2%) stddev-sans-first:3.09 19:53:51 INFO - PROCESS | 26501 | Values: 78.0 73.0 73.0 75.0 78.0 72.0 73.0 72.0 81.0 73.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#11] stanford-crypto-ccm Cycles:10 Average:126.80 Median:124.50 stddev:14.20 (11.4%) stddev-sans-first:14.82 19:53:51 INFO - PROCESS | 26501 | Values: 134.0 122.0 119.0 120.0 117.0 127.0 121.0 116.0 164.0 128.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.90 Median:154.00 stddev:2.02 (1.3%) stddev-sans-first:2.15 19:53:51 INFO - PROCESS | 26501 | Values: 154.0 155.0 156.0 153.0 153.0 154.0 152.0 158.0 153.0 151.0 19:53:51 INFO - PROCESS | 26501 | 19:53:51 INFO - PROCESS | 26501 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:63.00 stddev:2.00 (3.2%) stddev-sans-first:2.07 19:53:51 INFO - PROCESS | 26501 | Values: 61.0 60.0 64.0 61.0 64.0 65.0 62.0 65.0 60.0 61.0 19:53:51 INFO - PROCESS | 26501 | -------- Summary: end -------- 19:53:51 INFO - PROCESS | 26501 | 19:53:52 INFO - TEST-INFO | 26501: exit 0 19:53:52 INFO - TEST-OK | kraken | took 59262ms 19:53:52 INFO - SUITE-END | took 1171s 19:53:52 INFO - Completed test suite (00:19:31) 19:53:52 INFO - javascript benchmark 19:53:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3268.395346860831, 3282.885099021534, 3294.55523504327, 3318.518814771857, 3342.799432034078, 5662.405447428017, 5694.145878118285, 5732.5987180897355, 5755.223044888939, 5768.798092066779, 5671.461211184931, 5718.971405142979, 5730.3123625164935, 5736.368999410072, 5765.509931655814, 5158.329417175772, 5210.6352555320955, 5210.791568337262, 5236.816711415101, 5262.210668399733, 5052.444231134572, 5094.057599344114, 5265.7893684252585, 5272.947270527303, 5307.787688492455, 5140.100482415581, 5145.76844042019, 5204.609654275944, 5259.631825772198, 5275.920861187085, 5260.158374660055, 5290.576753859682, 5295.7881684732565, 5297.4437684043005, 5298.178782288752, 5019.673721208146, 5106.64253502255, 5172.965406918632, 5227.843164705065, 5230.110881150214, 5582.692951887645, 5597.216389705444, 5671.00757367459, 5761.827145185651, 5773.2494775679315, 6077.513798896078, 6112.572119951606, 6127.662978536178, 6128.3258841527395, 6130.356312587159, 6169.7532098716, 6175.907361389583, 6190.226221722285, 6194.721237544276, 6194.876102477971, 5575.135853942646, 5594.944050559483, 5641.71791410432, 5661.660300381949, 5740.483356497852, 6205.875882482415, 6216.378362163748, 6216.595921265103, 6218.595791273553, 6221.47117495018, 5922.378150294243, 5971.164037034819, 5980.55145864062, 5981.700914954322, 5985.431384018511, 5944.613600115978, 6009.849753756191, 6061.6666083365835, 6079.209702738615, 6131.315499379051, 6038.547108966757, 6050.878982420327, 6083.11794789753, 6100.145979562864, 6106.72519736613, 6078.422549857756, 6087.939120608826, 6096.908546371808, 6098.207233059763, 6108.908366374508, 4572.611328037087, 4580.908381832412, 4609.3086037094035, 4616.238320677118, 4681.836135735233, 4146.212219678251, 4146.4402305688345, 4158.7296825706235, 4159.9792001039805, 4172.4993000840095, 5739.971300143556, 5765.3369862465515, 5766.394528574523, 5769.278840144982, 5797.652140871562, 5921.496672782861, 6032.065029920369, 6083.087536869417, 6099.512039036867, 6114.905704492704, 5826.329972053183, 5835.5915085944425, 5839.182514447981, 5840.474357307863, 5868.207791948118, 5148.922766158511, 5183.948160518422, 5194.714290713971, 5200.089984252741, 5234.555062819703, 6083.7566497340495, 6213.813585592439, 6282.623042617458, 6283.183186185766, 6380.585261958051, 6112.205413296421, 6113.572049956639, 6117.388261173847, 6117.571769976059, 6117.877642447215, 6358.745650173942, 6363.109164717036, 6364.109024736448, 6369.363063693593, 6375.553711240169, 5830.679312637676, 5842.211301474416, 5861.706914654335, 5870.5010074144175, 6008.128821320972, 6032.728527216198, 6039.184710064085, 6043.456088952015, 6045.335013148462, 6046.758129674764, 5863.061910094364, 5899.174115623902, 5914.4381283777975, 5921.792737254175, 5924.200232968667, 5715.771369145187, 5813.215215945793, 5856.326522449803, 5885.470307672159, 5976.521878249642, 5886.0, 5886.6173698709445, 5890.646561206341, 5892.263467066617, 5893.263342082239, 5940.702964851826, 5969.074793407029, 5983.37174596661, 5985.341612422717, 5988.850278743066, 5660.858478537904, 5722.513586345207, 5840.240768700041, 5842.649441033552, 5902.881942361043], "value": 5609.082283837268, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.2037508613198, 605.9242594675668, 608.9573729838913, 620.1534904854873, 651.8272657745696, 35426.82286588567, 46845.29732054018, 48006.27990580144, 48067.0, 48082.27876581854, 50799.0, 51070.489295107036, 51083.74458127713, 51242.0, 51332.74333628336, 8585.656573737075, 9323.580438880233, 9488.857667134998, 9492.81014379712, 10667.573297068107, 10813.08088812452, 11233.943830280878, 11279.887201128007, 11281.435928203598, 11291.040261577777, 11399.316041037522, 11455.14086443516, 11461.025812805921, 11462.656120316404, 11463.140264480156, 11320.547178112865, 11341.206115571871, 11352.886471135265, 11354.943225283903, 11357.204995650307, 11097.778044439066, 11221.214514983954, 11253.0997520199, 11256.493457794419, 11263.66209013731, 46719.76640116812, 47146.5285347149, 47172.764136179445, 47181.0, 47224.0, 3843.2313537292375, 4105.753654780723, 4165.354370072643, 4299.892502687427, 4534.863954081383, 34741.47887781685, 35427.11432214215, 35493.46759798605, 35523.82238088793, 35904.461433078526, 4666.556677115668, 4669.486356500782, 4683.812647494096, 4710.882227944295, 4885.0, 354853.0, 356029.9999999974, 356477.0, 358341.0, 359315.0, 65462.34537654562, 65787.34212657908, 65808.34191658118, 65826.0, 65834.0, 40843.1831363377, 41773.0, 41798.0, 43839.78080109643, 70143.64928175429, 18181.363652272372, 18297.268109275745, 18306.359277425225, 18311.267549297878, 18377.356792512197, 13716.519921802688, 13895.0, 14061.78907316391, 14199.574012779633, 14247.928760356132, 21698.56602867934, 21714.782852171593, 21737.45656358572, 21740.782592174193, 21887.343379698636, 7170.282971702788, 7214.49498535108, 7324.2309557496765, 7424.96287518559, 7496.7376141834775, 1060.4697651174413, 1135.9488823002985, 1138.9145814063813, 1152.4353066997278, 1203.3141109567462, 36140.0, 36174.0, 36553.45169822455, 36636.633633663856, 36658.45012324817, 1179.3572502985721, 1183.8520184976878, 1210.4552951172009, 1227.6501197158946, 1267.33464930912, 17345.826541734674, 17467.388641397745, 17549.29802807899, 17774.555636108944, 17839.0, 155158.22420887824, 155279.0, 155321.0, 155635.0, 156745.21627392017, 67215.32784672189, 67411.66294168597, 67639.32360676429, 67686.32313676899, 67807.0, 1334.292824802856, 1423.9992810101824, 1455.9417623295162, 1468.383279022813, 1482.3403585404392, 35896.64103359037, 36324.0, 37394.62605373911, 37408.251834963696, 37472.81263593664, 57099.14351284651, 57708.0, 57792.422075778704, 57799.4220057794, 57803.0, 470885.0, 472306.0, 472920.0, 474028.0, 474859.0, 28144.607708345022, 31212.843935780176, 31414.68585314209, 31510.68489315169, 32739.508907366886, 48305.75847120742, 54126.0, 54270.45729542654, 54965.72517137389, 55313.72343138258, 44226.0, 44827.551724482335, 45473.772631136635, 45590.82980313979, 46582.30126548172, 44930.77534612306, 46018.0, 46220.0, 47380.763096184295, 47479.762601186776, 44419.11161776811, 44522.0, 44662.0, 44770.3284450727, 44994.77502612466, 37740.81129594444, 39973.0, 40262.0, 40884.386734198415, 41135.0, 30014.549781752856, 30120.84939575288, 30721.69278307188, 30739.231519212197, 33501.77765349715], "value": 21457.527140418868, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.44860970336686, 395.427742534811, 426.2540554030447, 429.120303378075, 434.43740356238686, 19084.71372929407, 20502.794972050277, 20724.274650387222, 21160.365189044314, 21369.57260854782, 18882.24471021161, 19216.9039154804, 19356.0, 19545.51136221595, 21214.151433942625, 5699.629524080932, 5921.378255283176, 5926.762929482816, 5929.28848538176, 5930.229070220866, 5790.449907258803, 5838.9124163137585, 5839.270091238595, 5874.706264686749, 5875.500582450497, 5724.856878578027, 5837.678927658977, 5874.3244526879525, 5881.617694849842, 5883.588148829584, 5796.65220086794, 5801.07182850744, 5831.88336233273, 5846.795362162304, 5868.530517558585, 5214.191800270964, 5262.368515778093, 5268.262443257947, 5326.733663316857, 5329.094054010828, 22865.77134228653, 23131.421714457105, 23323.76676233233, 23470.882645586833, 23630.40923976897, 2531.4683916377585, 2539.390546268882, 2539.606361014046, 2576.6135079738, 2745.917622471309, 15201.08793472384, 15300.311485983157, 15393.769093463607, 15529.844701552953, 15806.762898556532, 1994.7606287245478, 2091.4248581640018, 2122.2466024561368, 2145.495808485005, 2210.602091623507, 61072.69463652653, 65080.674596627185, 77629.61185194095, 83744.0, 87953.0, 11333.0, 11345.262557933707, 11526.250793698382, 11589.884101159048, 11799.410029498491, 13743.587692369045, 14186.503472378417, 14576.271186440848, 14609.488667896572, 15323.846761532464, 7985.640646170937, 8074.636641351153, 8345.499270043816, 8487.278581320534, 8495.065542790288, 8069.273765361146, 8070.515769053875, 8073.7577872663915, 8261.934516370908, 8287.62705678246, 6352.618842869442, 6438.581492202992, 6539.215294164731, 6566.217287866848, 6569.80290591283, 1903.7144428335585, 1981.890995995234, 1999.9500012499514, 2009.65835807913, 2022.3427386099577, 1047.36634336227, 1068.572570971602, 1091.028984204059, 1116.4473585575192, 1135.6706555098929, 20683.276085337242, 21398.144074237167, 21468.24861129884, 21487.0, 21589.352319430443, 1062.8512008318842, 1066.36018388966, 1067.412922892406, 1069.417167643636, 1123.9943800280946, 10723.571057157626, 11877.0, 12132.090093243041, 12193.573224937261, 12213.389330533439, 547753.0, 561913.0, 600174.0, 617866.0, 623986.0, 95482.0, 101174.0, 102246.48876755718, 102914.0, 103966.4801676002, 1004.5128112865254, 1037.7301901505512, 1065.5524679634418, 1076.5048077884105, 1077.563586747353, 18289.26842926268, 18445.169967351234, 18683.1592578337, 18799.342023029127, 19140.617187656448, 44554.3316850254, 44857.55142448534, 45132.32301515545, 45395.54604453913, 45699.08601828011, 60162.39837601743, 62571.0, 66371.33628663652, 67498.0, 67658.66170669116, 7990.560519171501, 8140.715074972348, 8386.622601983048, 8793.164649358301, 9105.453672779655, 13732.519361822287, 14312.57062288133, 14369.425222990963, 14379.424823006962, 14524.419023239374, 11998.340091294713, 12407.875921240671, 12586.307753073668, 12689.873101269239, 12807.61577152687, 12063.517459301529, 12353.073519486254, 12574.811377829157, 12699.111062225555, 12712.364381781059, 12173.147879648339, 12288.1398302118, 12580.119391642496, 12603.0, 12875.484980600671, 8298.556099218757, 8449.45078569891, 8527.872081918653, 8551.657933682583, 8706.347023973101, 10747.623833165802, 12294.323812190416, 12484.625461236177, 12561.685957851127, 13068.5426010093], "value": 9839.736259717089, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1289.1942535915052, 1482.599698081518, 1557.5483109898128, 1671.264643556835, 1789.8568114550828, 9233.861492077616, 9699.806003879918, 9749.122578967883, 10034.247431442653, 10050.49747512625, 9941.254405919548, 10063.446510441923, 10081.09270165685, 10085.092341689247, 10086.949565252164, 2554.0549996501272, 2589.4562141950214, 2603.635491031257, 2603.8437693738438, 2604.440045390241, 2504.7370026147264, 2588.4693637804203, 2590.7927365810688, 2592.481503699258, 2593.1313010141625, 2557.4629327841085, 2559.872006399682, 2602.687677478705, 2603.5313643544155, 2609.23027706826, 2446.987765061172, 2453.815963802722, 2463.1132792194708, 2499.4251322195996, 2502.73721259267, 2437.9634305485433, 2444.4622183119686, 2444.792192663626, 2446.1193970170725, 2484.9751502484924, 9860.90139098607, 9935.05616966387, 10006.399616023062, 10039.04629060238, 10044.598216071421, 5289.338832645919, 5480.808171713971, 5491.0, 5491.2312276281355, 5497.615166938316, 3565.144365352323, 3578.7474383965664, 3606.1511405989236, 3612.277544491086, 3700.5004324416127, 1188.6315242274923, 1199.7420554580808, 1204.3737256626594, 1221.2916508425092, 1243.3658833994728, 13912.721745565032, 14011.299435028208, 14045.157290562598, 14059.929700351433, 14136.575902722934, 4840.467548569654, 4864.416270047617, 4887.584555312838, 4890.633202509826, 5080.49195080489, 10877.0754485868, 10886.510107045202, 10932.890671093346, 10950.0, 10972.835407468894, 5522.282103326622, 5528.143137813645, 5533.889322213614, 5535.7785688572, 5536.280283563111, 4673.275642275453, 4751.192297309466, 5112.233165025276, 5205.9219111713355, 5246.554042906319, 4255.340422234559, 4284.89287767802, 4300.505441874162, 4332.653387728974, 4335.241332766753, 1250.2186133666457, 1257.666718319646, 1263.6714454241962, 1283.146707439561, 1287.1697754948007, 967.7822489939848, 1034.1158309645296, 1059.0362769879373, 1067.1729409707539, 1076.2358725304964, 6628.602283862983, 6770.059342741675, 6920.757773477904, 6944.895826562605, 6950.617716025566, 474.4235753559442, 478.1751478699216, 481.6002717744334, 493.78520343650627, 505.4793562630496, 4659.091477161988, 4701.576858082789, 4794.760261986957, 4849.951500485021, 4854.538818812207, 11746.0, 12322.322272274932, 12682.429290681941, 12790.424430900632, 12865.099443038898, 12069.698257543456, 12430.0, 12456.128071035122, 12461.252324860538, 12471.875281247252, 9141.862872056925, 9318.72043838686, 9326.860097098548, 9328.999999999864, 9335.579898904565, 6706.899396509154, 6815.931840681728, 6902.0, 7001.649917504206, 7009.264027277062, 11449.771004579696, 11531.077513798708, 11659.650210493699, 11692.766144676889, 11793.11551633648, 10554.050135487843, 11132.276402033842, 11622.244554104294, 11639.301641901513, 11815.291082535075, 8502.277306429023, 8850.159234872677, 8934.955325223333, 8944.68693595721, 9019.052999435227, 9224.815503690023, 9377.249820014518, 9383.953080234554, 9389.812203756022, 9403.576839042396, 8061.596920154087, 8066.274035336848, 8171.387145964195, 8734.388592798443, 9021.0, 8585.18440748128, 8687.174718401742, 8708.134446602166, 8716.128387161232, 8996.910030899608, 7462.0, 7616.657250423634, 7624.351930086005, 7707.421943354158, 7850.411219158472, 5767.7404516796005, 5845.590808643354, 5887.61730487517, 5950.672713000827, 5961.612495187799, 5865.765369385347, 5941.851453713692, 5952.76188952437, 5975.521958243417, 5998.49012833914], "value": 5006.43648701963, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.31493587504366, 353.589835790483, 361.9221867298532, 363.96542328478785, 369.10307951677413, 5497.587680923927, 5526.861828454285, 5528.9723551382285, 5563.972180139093, 5582.692951887645, 5467.6719396836215, 5487.938925195356, 5499.477549632788, 5501.367342755584, 5502.504774570288, 4254.659627229822, 4328.696991210617, 4332.700189943021, 4408.448943882015, 4413.161499315136, 4345.283028300327, 4352.630026447756, 4389.4952080510675, 4397.801099450275, 4417.845375411861, 4352.912941741163, 4358.692392282319, 4368.541303163154, 4369.956300437003, 4376.212281789277, 4169.8741339838225, 4343.109662519176, 4357.324614684729, 4366.061296821167, 4392.407025051609, 3818.522684664417, 3829.3107240696663, 3841.0, 3848.4034974578985, 3869.361555343365, 5344.5724342052545, 5355.866103347408, 5357.276767636358, 5366.168243922199, 5410.701431656414, 4843.636727245435, 4916.803327866881, 4963.553280204799, 4977.377827771528, 4978.327925730016, 5721.995952125126, 5729.856753581152, 5733.283339582552, 5735.283089613798, 5786.363500014995, 4214.599613036788, 4239.957600424018, 4244.61798438139, 4245.6815738819405, 4247.554006829269, 5646.604737668365, 5787.797427090031, 5814.738336774855, 6001.459868611802, 6042.184305118797, 4236.597523235349, 4239.618434340924, 4259.403683484315, 4289.270823959934, 4291.356296555542, 4324.113556720865, 4337.9783101084295, 4342.32693932441, 4346.717463364871, 4351.673624478177, 4741.620670346433, 4819.542143496362, 4904.705717656952, 4913.263010548446, 4938.506149385033, 5705.258316418922, 5710.7430165642645, 5713.771449141987, 5723.594461295523, 5748.798792042258, 1479.408236705305, 1489.4489039055618, 1490.4038384646228, 1500.7298686236581, 1503.6541595432893, 933.2067742419025, 964.9710508684751, 972.7033254857195, 974.340051658975, 981.5582987655583, 4942.2092465206115, 4955.515258690891, 4958.256261560795, 4966.205407134913, 4969.627277954168, 5160.561352285098, 5164.715940623227, 5204.76578553966, 5208.609354298443, 5242.2700508831995, 4628.421447319085, 4846.466888642247, 4948.911239527299, 4971.229459433794, 5000.724960127228, 2722.006467639297, 2725.2369336585393, 2729.7952653551065, 2736.945261094767, 2760.917172484829, 5296.629235953524, 5311.380029091087, 5327.280817089721, 5333.066713325153, 5353.598480114007, 5219.677243626626, 5268.525832675077, 5596.664200148004, 6005.309389420186, 6074.453299203093, 6206.472449841724, 6211.16149319845, 6234.407731265523, 6243.937560624427, 6255.999040153553, 5640.520555752807, 5971.880562388641, 5977.491913187254, 5977.760889564369, 6016.398360163948, 5834.18321435008, 5847.239858818326, 5849.736761845812, 5851.561132914963, 5869.0338736523745, 5917.733701983335, 6021.7956408717555, 6047.516198704181, 6057.121717351048, 6191.752329906753, 4142.399352093989, 4234.491860976703, 4271.359296105485, 4285.7642829644665, 4301.91396172081, 5794.710264486843, 5977.551683623658, 5982.282126144891, 6007.219061521974, 6007.939920600738, 5703.258576385043, 5752.309722833287, 5757.481826635623, 5779.219805326227, 5787.94212057874, 5764.279465066867, 5782.2483077199695, 5786.276715410574, 5813.360530341574, 5825.883482330414, 5523.502884740393, 5749.655020698771, 5767.884642307215, 5788.82633520995, 5818.0981947798155, 5818.854528636818, 5826.621269617462, 5840.2115714378015, 5914.467697907209, 5938.8218353449465, 5489.780408783604, 5685.175649530712, 5695.971520142372, 5745.626534275259, 5760.971195143998], "value": 4343.9371406776745, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.9089304729956, 807.2613558593887, 830.2195935820329, 857.8713193021046, 869.1091631078148, 15320.616984575378, 16306.592335191612, 16347.182640867939, 16376.75434868475, 16417.75373369399, 16474.09392483413, 16551.0, 16588.253528591213, 16638.584035399123, 16639.33442662295, 734.5299008634478, 863.0334025891011, 881.4579033894157, 886.57887503436, 892.6072528087653, 590.9645421274737, 592.4904582059426, 593.2199158107097, 598.5062323583039, 600.1837500998638, 936.7704912296497, 940.6848705683605, 940.9012053734363, 941.5339406993547, 942.114412452296, 928.679605536089, 933.8039011807494, 935.6304259817368, 938.5776400619749, 939.3518472254191, 687.6939761805999, 690.4752388185016, 691.1774987764544, 692.1037256752468, 692.1728534401392, 16707.749383759252, 16707.916460417742, 16712.66574668512, 16715.247813848408, 16752.078635675032, 12798.48806047757, 13345.733085338336, 13891.374888130058, 14520.564383068524, 15058.0, 13772.586822395344, 13966.301684915714, 14059.312882454069, 14160.291985400792, 14387.208703521303, 4844.491328410501, 4881.389826271716, 4900.58345040672, 5003.799848006075, 5008.248762685626, 88588.55705721494, 89154.55422722909, 90301.0, 90447.54776226143, 95108.9999999993, 10909.0, 11668.824967625493, 11700.122490813223, 11884.573851137919, 11935.343556104497, 20530.178792848117, 20665.070071846803, 20700.689489657667, 20768.065437055368, 20784.58430831405, 8651.52416617092, 8871.955640221757, 8888.911110888937, 8902.332325075646, 8928.15182557656, 4852.805887764521, 4986.650934434628, 4989.800407983713, 5000.524950129732, 5273.314469119066, 3821.3694740367528, 3901.8634347797692, 3990.2418540477215, 3999.0202400412086, 4007.198560287954, 660.0989649128904, 661.153723234261, 669.8760729265101, 672.8082496488478, 679.6907407129744, 603.5774957529746, 619.1548536248079, 635.0537698828712, 657.0341597851151, 679.67035987546, 8526.488410695378, 8762.737117886474, 8868.778280543038, 8906.55467226636, 9075.773105672279, 635.1140159477503, 664.3887623386496, 666.1140682891743, 667.4660271782651, 683.852971611106, 4303.1608836277255, 4366.43236379275, 4440.645603091024, 4500.504944456107, 4520.864374068783, 132881.0, 133152.0, 133739.0, 136407.31796340956, 136664.0, 90981.54509227411, 94274.0, 113002.0, 115712.42143789228, 115865.42067289779, 57447.712761436764, 80892.0, 85588.57205714055, 91953.0, 92128.0, 8755.0, 8807.691730789391, 8954.552272386485, 9082.54587270647, 9234.261259099121, 49005.50994490009, 50456.24315635342, 52524.73737631287, 54699.0, 56026.71986640041, 53586.73206633942, 69492.0, 81821.59089204516, 82090.0, 84050.21774020306, 12995.415206315549, 13033.0, 13109.475620975054, 13111.344432778515, 13127.409266583218, 12896.871031289567, 13148.474061037452, 13325.13386629866, 13380.598582042554, 13508.189508629514, 12224.403842983216, 12386.75226495483, 12649.87350126487, 12732.490700372251, 13137.0, 13255.0, 13259.270740109387, 13384.665383365495, 13418.731625367633, 13594.86405135936, 12665.176763510342, 13082.215067096005, 13513.527026554024, 13649.0, 13956.162630242217, 10489.527971241465, 10899.0, 11020.063294620048, 11380.0, 11563.942180289045, 9070.637174512945, 9333.206677432494, 9490.620375184915, 10363.63727269542, 10421.895781042092], "value": 7697.869260396808, "unit": "score"}], "name": "dromaeo_css", "value": 7636.2517070128115, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 120.0, 109.0, 110.0, 111.0, 108.0, 111.0, 109.0, 110.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 117.0, 145.0, 177.0, 116.0, 157.0, 144.0, 115.0, 174.0, 117.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 159.0, 161.0, 160.0, 162.0, 161.0, 160.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 180.0, 75.0, 74.0, 76.0, 75.0, 175.0, 76.0], "value": 95.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 153.0, 84.0, 84.0, 83.0, 152.0, 84.0, 83.0, 143.0], "value": 103.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 116.0], "value": 116.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 50.0, 49.0, 50.0, 53.0, 50.0, 51.0, 50.0, 51.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 61.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 73.0, 75.0, 78.0, 72.0, 73.0, 72.0, 81.0, 73.0], "value": 74.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 122.0, 119.0, 120.0, 117.0, 127.0, 121.0, 116.0, 164.0, 128.0], "value": 126.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 155.0, 156.0, 153.0, 153.0, 154.0, 152.0, 158.0, 153.0, 151.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 64.0, 61.0, 64.0, 65.0, 62.0, 65.0, 60.0, 61.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1497.3, "alertThreshold": 2.0}]} 19:53:52 INFO - Return code: 0 19:53:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:53:52 INFO - # TBPL SUCCESS # 19:53:52 INFO - Running post-action listener: _resource_record_post_action 19:53:52 INFO - [mozharness: 2016-10-25 02:53:52.554687Z] Finished run-tests step (success) 19:53:52 INFO - Running post-run listener: _resource_record_post_run 19:53:52 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292061184; Read time: 20; Write time: 179868 19:53:52 INFO - TinderboxPrint: CPU usage
12.9% 19:53:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 19:53:52 INFO - TinderboxPrint: I/O write bytes / time
292,061,184 / 179,868 19:53:52 INFO - TinderboxPrint: CPU idle
8,244.7 (87.1%) 19:53:52 INFO - TinderboxPrint: CPU user
1,181.2 (12.5%) 19:53:52 INFO - TinderboxPrint: Swap in / out
0 / 0 19:53:52 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70115328; Read time: 0; Write time: 63004 19:53:52 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 221945856; Read time: 0; Write time: 116864 19:53:53 INFO - Running post-run listener: _upload_blobber_files 19:53:53 INFO - Blob upload gear active. 19:53:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:53:53 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 19:53:53 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'] 19:53:53 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 19:53:53 INFO - (blobuploader) - INFO - Open directory for files ... 19:53:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:53:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:53:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:54 INFO - (blobuploader) - INFO - Done attempting. 19:53:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:53:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:53:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:55 INFO - (blobuploader) - INFO - Done attempting. 19:53:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:53:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:53:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:53:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:53:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:53:59 INFO - (blobuploader) - INFO - Done attempting. 19:53:59 INFO - (blobuploader) - INFO - Iteration through files over. 19:53:59 INFO - Return code: 0 19:53:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:53:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:53:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de05b0f482a50c1930b53f64e06d5889eea84e7c65c5f41a630d74045f71fdd7217fc6ecfcb403a6ec1cdf568f89c97ff6803bd90a79a1faa100e1367e706a7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1dff51acf63d1a283d75ddffdc4ef930b5dc2803c802b01ffb008796a2915013a4857aaa546398c443fd84326493467c714e575be96e8c193012fea5cf97008", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e4f1ed7d618db175d4fae14569dc760b4b4d43646d45ff341d3c6e2c2467a4eca990053c8808d31e6efd6bc220753a45bef7e9dfb2d856579810167d0d96252"} 19:53:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:53:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:53:59 INFO - Contents: 19:53:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de05b0f482a50c1930b53f64e06d5889eea84e7c65c5f41a630d74045f71fdd7217fc6ecfcb403a6ec1cdf568f89c97ff6803bd90a79a1faa100e1367e706a7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1dff51acf63d1a283d75ddffdc4ef930b5dc2803c802b01ffb008796a2915013a4857aaa546398c443fd84326493467c714e575be96e8c193012fea5cf97008", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e4f1ed7d618db175d4fae14569dc760b4b4d43646d45ff341d3c6e2c2467a4eca990053c8808d31e6efd6bc220753a45bef7e9dfb2d856579810167d0d96252"} 19:53:59 INFO - Running post-run listener: copy_logs_to_upload_dir 19:53:59 INFO - Copying logs to upload dir... 19:53:59 INFO - mkdir: /builds/slave/test/build/upload/logs 19:53:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1673.906123 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-24 19:53:58.994259) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 19:53:58.998132) ========= 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=0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206 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/de05b0f482a50c1930b53f64e06d5889eea84e7c65c5f41a630d74045f71fdd7217fc6ecfcb403a6ec1cdf568f89c97ff6803bd90a79a1faa100e1367e706a7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1dff51acf63d1a283d75ddffdc4ef930b5dc2803c802b01ffb008796a2915013a4857aaa546398c443fd84326493467c714e575be96e8c193012fea5cf97008", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e4f1ed7d618db175d4fae14569dc760b4b4d43646d45ff341d3c6e2c2467a4eca990053c8808d31e6efd6bc220753a45bef7e9dfb2d856579810167d0d96252"} build_url:https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011542 build_url: 'https://queue.taskcluster.net/v1/task/JoSCp5ttR7uGDjwgG0QQsg/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/de05b0f482a50c1930b53f64e06d5889eea84e7c65c5f41a630d74045f71fdd7217fc6ecfcb403a6ec1cdf568f89c97ff6803bd90a79a1faa100e1367e706a7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1dff51acf63d1a283d75ddffdc4ef930b5dc2803c802b01ffb008796a2915013a4857aaa546398c443fd84326493467c714e575be96e8c193012fea5cf97008", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e4f1ed7d618db175d4fae14569dc760b4b4d43646d45ff341d3c6e2c2467a4eca990053c8808d31e6efd6bc220753a45bef7e9dfb2d856579810167d0d96252"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-24 19:53:59.023911) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:53:59.024260) ========= 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=0333a256a5e4f052382dd180000001b1-1477362363.698025-1477921206 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004509 ========= master_lag: 0.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-24 19:53:59.210818) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 19:53:59.211144) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-24 19:53:59.211454) ========= ========= Total master_lag: 0.27 =========